builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0221 starttime: 1497011706.99 results: success (0) revision: 1742b1bdadd13a02df95ca690bea9cc42ff40c91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:06.992177) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:06.998694) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:06.999069) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:07.045290) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:07.045598) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CJj1ErsY2o/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-09 05:35:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2017-06-09 05:35:07 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106459 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:07.177759) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:07.178106) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CJj1ErsY2o/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.022125 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:07.227630) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-09 05:35:07.228011) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1742b1bdadd13a02df95ca690bea9cc42ff40c91 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 1742b1bdadd13a02df95ca690bea9cc42ff40c91 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CJj1ErsY2o/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-09 05:35:07,310 truncating revision to first 12 chars 2017-06-09 05:35:07,310 Setting DEBUG logging. 2017-06-09 05:35:07,310 attempt 1/10 2017-06-09 05:35:07,310 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1742b1bdadd1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-09 05:35:09,173 unpacking tar archive at: mozilla-central-1742b1bdadd1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.124714 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-09 05:35:09.374584) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:09.374951) ========= script_repo_revision: 1742b1bdadd13a02df95ca690bea9cc42ff40c91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:09.375381) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:09.375701) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-09 05:35:09.396056) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-09 05:35:09.396415) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CJj1ErsY2o/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:35:09 INFO - MultiFileLogger online at 20170609 05:35:09 in /builds/slave/test 05:35:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:35:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:35:09 INFO - {'append_to_log': False, 05:35:09 INFO - 'base_work_dir': '/builds/slave/test', 05:35:09 INFO - 'blob_upload_branch': 'Firefox', 05:35:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:35:09 INFO - 'branch': 'Firefox', 05:35:09 INFO - 'buildbot_json_path': 'buildprops.json', 05:35:09 INFO - 'config_files': ('talos/mac_config.py',), 05:35:09 INFO - 'default_actions': ('clobber', 05:35:09 INFO - 'read-buildbot-config', 05:35:09 INFO - 'download-and-extract', 05:35:09 INFO - 'populate-webroot', 05:35:09 INFO - 'create-virtualenv', 05:35:09 INFO - 'install', 05:35:09 INFO - 'setup-mitmproxy', 05:35:09 INFO - 'run-tests'), 05:35:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:35:09 INFO - 'download_minidump_stackwalk': True, 05:35:09 INFO - 'download_symbols': 'ondemand', 05:35:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:35:09 INFO - 'tooltool.py': '/tools/tooltool.py', 05:35:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:35:09 INFO - '/tools/misc-python/virtualenv.py')}, 05:35:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:35:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:35:09 INFO - 'gecko_profile': False, 05:35:09 INFO - 'gecko_profile_interval': 0, 05:35:09 INFO - 'installer_path': 'installer.exe', 05:35:09 INFO - 'log_level': 'info', 05:35:09 INFO - 'log_name': 'talos', 05:35:09 INFO - 'log_to_console': True, 05:35:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:35:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:35:09 INFO - 'opt_config_files': (), 05:35:09 INFO - 'pip_index': False, 05:35:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:35:09 INFO - 'cmd': ('bash', 05:35:09 INFO - '-c', 05:35:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:35:09 INFO - 'enabled': True, 05:35:09 INFO - 'halt_on_failure': False, 05:35:09 INFO - 'name': 'check_screen_resolution'},), 05:35:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:35:09 INFO - 'cmd': ('bash', 05:35:09 INFO - '-c', 05:35:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:35:09 INFO - 'enabled': True, 05:35:09 INFO - 'halt_on_failure': False, 05:35:09 INFO - 'name': 'check_screen_resolution'},), 05:35:09 INFO - 'run_cmd_checks_enabled': True, 05:35:09 INFO - 'suite': 'dromaeojs-e10s', 05:35:09 INFO - 'system_bits': '32', 05:35:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:35:09 INFO - 'title': 't-yosemite-r7-0221', 05:35:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:35:09 INFO - 'use_talos_json': True, 05:35:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:35:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:35:09 INFO - 'work_dir': 'build'} 05:35:09 INFO - [mozharness: 2017-06-09 12:35:09.596919Z] Running clobber step. 05:35:09 INFO - Running pre-action listener: _resource_record_pre_action 05:35:09 INFO - Running main action method: clobber 05:35:09 INFO - rmtree: /builds/slave/test/build 05:35:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:35:09 INFO - Running post-action listener: _resource_record_post_action 05:35:09 INFO - [mozharness: 2017-06-09 12:35:09.966014Z] Finished clobber step (success) 05:35:09 INFO - [mozharness: 2017-06-09 12:35:09.966144Z] Running read-buildbot-config step. 05:35:09 INFO - Running pre-action listener: _resource_record_pre_action 05:35:09 INFO - Running main action method: read_buildbot_config 05:35:09 INFO - Using buildbot properties: 05:35:09 INFO - { 05:35:09 INFO - "project": "", 05:35:09 INFO - "product": "firefox", 05:35:09 INFO - "who": "cbook@mozilla.com", 05:35:09 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 05:35:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:35:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:35:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:35:09 INFO - "stage_platform": "macosx64", 05:35:09 INFO - "basedir": "/builds/slave/test", 05:35:09 INFO - "buildnumber": 89, 05:35:09 INFO - "platform": "yosemite_r7", 05:35:09 INFO - "parent_task_id": "LtnFcVSkRyWnwHEf4VnYfA", 05:35:09 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:35:09 INFO - "slavebuilddir": "test", 05:35:09 INFO - "taskId": "BUumhV5SQdC8KM5oRp2Ogw", 05:35:09 INFO - "branch": "mozilla-central", 05:35:09 INFO - "script_repo_revision": "production", 05:35:09 INFO - "revision": "1742b1bdadd13a02df95ca690bea9cc42ff40c91", 05:35:09 INFO - "slavename": "t-yosemite-r7-0221", 05:35:09 INFO - "repo_path": "mozilla-central" 05:35:09 INFO - } 05:35:09 INFO - Finding installer, test and symbols from parent task. 05:35:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BUumhV5SQdC8KM5oRp2Ogw'}, attempt #1 05:35:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BUumhV5SQdC8KM5oRp2Ogw'}, attempt #1 05:35:11 INFO - Task dependencies: Xdquxrm5TTS8POG7cigCAA LtnFcVSkRyWnwHEf4VnYfA 05:35:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA'}, attempt #1 05:35:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:35:11 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/test_packages.json 05:35:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/target.crashreporter-symbols.zip 05:35:11 INFO - Running post-action listener: _resource_record_post_action 05:35:11 INFO - [mozharness: 2017-06-09 12:35:11.789158Z] Finished read-buildbot-config step (success) 05:35:11 INFO - [mozharness: 2017-06-09 12:35:11.789301Z] Running download-and-extract step. 05:35:11 INFO - Running pre-action listener: _resource_record_pre_action 05:35:11 INFO - Running main action method: download_and_extract 05:35:11 INFO - mkdir: /builds/slave/test/build/tests 05:35:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:11 INFO - trying https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:35:11 INFO - Downloading https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:35:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 05:35:12 INFO - Downloaded 1328 bytes. 05:35:12 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:35:12 INFO - Using the following test package requirements: 05:35:12 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:12 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:35:12 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:35:12 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:12 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:35:12 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:12 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:35:12 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:12 INFO - u'jsshell-mac.zip'], 05:35:12 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:12 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:35:12 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:35:12 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:12 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:35:12 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:12 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:35:12 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:12 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 05:35:12 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:35:12 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:35:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:35:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:35:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:35:12 INFO - Fetch https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:35:14 INFO - Content-Length response header: 59548331 05:35:14 INFO - Bytes received: 59548331 05:35:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:35:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:35:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:35:33 INFO - Fetch https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:35:35 INFO - Content-Length response header: 59548331 05:35:35 INFO - Bytes received: 59548331 05:35:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:35:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:35:52 INFO - Fetch https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:35:53 INFO - Content-Length response header: 14449999 05:35:53 INFO - Bytes received: 14449999 05:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:35:54 INFO - trying https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:35:54 INFO - Downloading https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:35:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 05:35:56 INFO - Downloaded 69309212 bytes. 05:35:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:35:56 INFO - mkdir: /builds/slave/test/properties 05:35:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:35:56 INFO - Writing to file /builds/slave/test/properties/build_url 05:35:56 INFO - Contents: 05:35:56 INFO - build_url:https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:35:56 INFO - Running post-action listener: _resource_record_post_action 05:35:56 INFO - Running post-action listener: set_extra_try_arguments 05:35:56 INFO - [mozharness: 2017-06-09 12:35:56.172727Z] Finished download-and-extract step (success) 05:35:56 INFO - [mozharness: 2017-06-09 12:35:56.172876Z] Running populate-webroot step. 05:35:56 INFO - Running pre-action listener: _resource_record_pre_action 05:35:56 INFO - Running main action method: populate_webroot 05:35:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:35:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:35:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:35:56 INFO - '--tppagecycles', 05:35:56 INFO - '7'], 05:35:56 INFO - 'tests': ['tsvgm']}}, 05:35:56 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:35:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:35:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:35:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:35:56 INFO - 'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:35:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:35:56 INFO - 'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['damp', 'tps']}, 05:35:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:35:56 INFO - 'tests': ['damp', 'tps']}, 05:35:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['dromaeo_dom']}, 05:35:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:35:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:35:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:35:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:35:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:35:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['a11yr', 05:35:56 INFO - 'ts_paint', 05:35:56 INFO - 'tpaint', 05:35:56 INFO - 'sessionrestore', 05:35:56 INFO - 'sessionrestore_no_auto_restore', 05:35:56 INFO - 'tabpaint']}, 05:35:56 INFO - 'other-e10s': {'tests': ['a11yr', 05:35:56 INFO - 'ts_paint', 05:35:56 INFO - 'tpaint', 05:35:56 INFO - 'sessionrestore', 05:35:56 INFO - 'sessionrestore_no_auto_restore', 05:35:56 INFO - 'tabpaint']}, 05:35:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:35:56 INFO - 'ts_paint', 05:35:56 INFO - 'tpaint', 05:35:56 INFO - 'sessionrestore', 05:35:56 INFO - 'sessionrestore_no_auto_restore', 05:35:56 INFO - 'tabpaint']}, 05:35:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:35:56 INFO - 'ts_paint', 05:35:56 INFO - 'tpaint', 05:35:56 INFO - 'sessionrestore', 05:35:56 INFO - 'sessionrestore_no_auto_restore', 05:35:56 INFO - 'tabpaint']}, 05:35:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['a11yr', 05:35:56 INFO - 'ts_paint', 05:35:56 INFO - 'tpaint', 05:35:56 INFO - 'sessionrestore', 05:35:56 INFO - 'sessionrestore_no_auto_restore', 05:35:56 INFO - 'tabpaint']}, 05:35:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['a11yr', 05:35:56 INFO - 'ts_paint', 05:35:56 INFO - 'tpaint', 05:35:56 INFO - 'sessionrestore', 05:35:56 INFO - 'sessionrestore_no_auto_restore', 05:35:56 INFO - 'tabpaint']}, 05:35:56 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 05:35:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 05:35:56 INFO - 'bloom_basic_ref']}, 05:35:56 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:35:56 INFO - 'talos_options': ['--mitmproxy', 05:35:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:35:56 INFO - '--firstNonBlankPaint'], 05:35:56 INFO - 'tests': ['quantum_pageload_google', 05:35:56 INFO - 'quantum_pageload_youtube', 05:35:56 INFO - 'quantum_pageload_amazon', 05:35:56 INFO - 'quantum_pageload_facebook']}, 05:35:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['tsvgx', 05:35:56 INFO - 'tsvgr_opacity', 05:35:56 INFO - 'tart', 05:35:56 INFO - 'tscrollx', 05:35:56 INFO - 'cart', 05:35:56 INFO - 'tsvg_static']}, 05:35:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:35:56 INFO - 'tsvgr_opacity', 05:35:56 INFO - 'tart', 05:35:56 INFO - 'tscrollx', 05:35:56 INFO - 'cart', 05:35:56 INFO - 'tsvg_static']}, 05:35:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:35:56 INFO - 'talos_options': ['--disable-e10s'], 05:35:56 INFO - 'tests': ['tp5o']}, 05:35:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:35:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:35:56 INFO - 'talos_options': ['--disable-e10s', 05:35:56 INFO - '--xperf_path', 05:35:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:56 INFO - 'tests': ['tp5n']}, 05:35:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:35:56 INFO - 'talos_options': ['--xperf_path', 05:35:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:56 INFO - 'tests': ['tp5n']}}, 05:35:56 INFO - 'talos.zip': {'path': '', 05:35:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:35:56 INFO - Running post-action listener: _resource_record_post_action 05:35:56 INFO - [mozharness: 2017-06-09 12:35:56.195067Z] Finished populate-webroot step (success) 05:35:56 INFO - [mozharness: 2017-06-09 12:35:56.195199Z] Running create-virtualenv step. 05:35:56 INFO - Running pre-action listener: _resource_record_pre_action 05:35:56 INFO - Running main action method: create_virtualenv 05:35:56 INFO - Creating virtualenv /builds/slave/test/build/venv 05:35:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:35:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:35:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:35:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:35:56 INFO - Using real prefix '/tools/python27' 05:35:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:35:57 INFO - Installing distribute.............................................................................................................................................................................................done. 05:35:59 INFO - Installing pip.................done. 05:35:59 INFO - Return code: 0 05:35:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:35:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:36:00 INFO - Reading from file tmpfile_stdout 05:36:00 INFO - Output received: 05:36:00 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:36:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:36:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:36:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:00 INFO - 'HOME': '/Users/cltbld', 05:36:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:00 INFO - 'LOGNAME': 'cltbld', 05:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:00 INFO - 'MOZ_NO_REMOTE': '1', 05:36:00 INFO - 'NO_EM_RESTART': '1', 05:36:00 INFO - 'PAGER': '/bin/cat', 05:36:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:00 INFO - 'PWD': '/builds/slave/test', 05:36:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:00 INFO - 'SHELL': '/bin/bash', 05:36:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:00 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:00 INFO - 'USER': 'cltbld', 05:36:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:00 INFO - 'XPC_FLAGS': '0x0', 05:36:00 INFO - 'XPC_SERVICE_NAME': '0', 05:36:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:36:00 INFO - Cleaning up... 05:36:00 INFO - Return code: 0 05:36:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:36:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:36:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:00 INFO - 'HOME': '/Users/cltbld', 05:36:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:00 INFO - 'LOGNAME': 'cltbld', 05:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:00 INFO - 'MOZ_NO_REMOTE': '1', 05:36:00 INFO - 'NO_EM_RESTART': '1', 05:36:00 INFO - 'PAGER': '/bin/cat', 05:36:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:00 INFO - 'PWD': '/builds/slave/test', 05:36:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:00 INFO - 'SHELL': '/bin/bash', 05:36:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:00 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:00 INFO - 'USER': 'cltbld', 05:36:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:00 INFO - 'XPC_FLAGS': '0x0', 05:36:00 INFO - 'XPC_SERVICE_NAME': '0', 05:36:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:00 INFO - Downloading/unpacking psutil>=3.1.1 05:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:36:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:36:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:36:03 INFO - Installing collected packages: psutil 05:36:03 INFO - Running setup.py install for psutil 05:36:03 INFO - building 'psutil._psutil_osx' extension 05:36:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:36:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:36:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:36:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:36:03 INFO - building 'psutil._psutil_posix' extension 05:36:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:36:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:36:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:36:03 INFO - ^ 05:36:03 INFO - 1 warning generated. 05:36:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:36:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:36:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:36:03 INFO - Successfully installed psutil 05:36:03 INFO - Cleaning up... 05:36:03 INFO - Return code: 0 05:36:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:36:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:36:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:36:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:03 INFO - 'HOME': '/Users/cltbld', 05:36:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:03 INFO - 'LOGNAME': 'cltbld', 05:36:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:03 INFO - 'MOZ_NO_REMOTE': '1', 05:36:03 INFO - 'NO_EM_RESTART': '1', 05:36:03 INFO - 'PAGER': '/bin/cat', 05:36:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:03 INFO - 'PWD': '/builds/slave/test', 05:36:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:03 INFO - 'SHELL': '/bin/bash', 05:36:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:03 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:03 INFO - 'USER': 'cltbld', 05:36:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:03 INFO - 'XPC_FLAGS': '0x0', 05:36:03 INFO - 'XPC_SERVICE_NAME': '0', 05:36:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:36:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:36:05 INFO - Installing collected packages: mozsystemmonitor 05:36:05 INFO - Running setup.py install for mozsystemmonitor 05:36:05 INFO - Successfully installed mozsystemmonitor 05:36:05 INFO - Cleaning up... 05:36:05 INFO - Return code: 0 05:36:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:36:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:36:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:36:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:05 INFO - 'HOME': '/Users/cltbld', 05:36:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:05 INFO - 'LOGNAME': 'cltbld', 05:36:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:05 INFO - 'MOZ_NO_REMOTE': '1', 05:36:05 INFO - 'NO_EM_RESTART': '1', 05:36:05 INFO - 'PAGER': '/bin/cat', 05:36:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:05 INFO - 'PWD': '/builds/slave/test', 05:36:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:05 INFO - 'SHELL': '/bin/bash', 05:36:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:05 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:05 INFO - 'USER': 'cltbld', 05:36:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:05 INFO - 'XPC_FLAGS': '0x0', 05:36:05 INFO - 'XPC_SERVICE_NAME': '0', 05:36:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:06 INFO - Downloading/unpacking jsonschema==2.5.1 05:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:36:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:07 INFO - Downloading functools32-3.2.3-2.tar.gz 05:36:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:36:07 INFO - warning: no files found matching '*.txt' 05:36:07 INFO - no previously-included directories found matching 'build' 05:36:07 INFO - no previously-included directories found matching 'dist' 05:36:07 INFO - no previously-included directories found matching '.git*' 05:36:07 INFO - Installing collected packages: jsonschema, functools32 05:36:07 INFO - Running setup.py install for functools32 05:36:08 INFO - warning: no files found matching '*.txt' 05:36:08 INFO - no previously-included directories found matching 'build' 05:36:08 INFO - no previously-included directories found matching 'dist' 05:36:08 INFO - no previously-included directories found matching '.git*' 05:36:08 INFO - Successfully installed jsonschema functools32 05:36:08 INFO - Cleaning up... 05:36:08 INFO - Return code: 0 05:36:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:36:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:36:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:08 INFO - 'HOME': '/Users/cltbld', 05:36:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:08 INFO - 'LOGNAME': 'cltbld', 05:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:08 INFO - 'MOZ_NO_REMOTE': '1', 05:36:08 INFO - 'NO_EM_RESTART': '1', 05:36:08 INFO - 'PAGER': '/bin/cat', 05:36:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:08 INFO - 'PWD': '/builds/slave/test', 05:36:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:08 INFO - 'SHELL': '/bin/bash', 05:36:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:08 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:08 INFO - 'USER': 'cltbld', 05:36:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:08 INFO - 'XPC_FLAGS': '0x0', 05:36:08 INFO - 'XPC_SERVICE_NAME': '0', 05:36:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:36:08 INFO - Cleaning up... 05:36:08 INFO - Return code: 0 05:36:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:36:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:36:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:08 INFO - 'HOME': '/Users/cltbld', 05:36:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:08 INFO - 'LOGNAME': 'cltbld', 05:36:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:08 INFO - 'MOZ_NO_REMOTE': '1', 05:36:08 INFO - 'NO_EM_RESTART': '1', 05:36:08 INFO - 'PAGER': '/bin/cat', 05:36:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:08 INFO - 'PWD': '/builds/slave/test', 05:36:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:08 INFO - 'SHELL': '/bin/bash', 05:36:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:08 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:08 INFO - 'USER': 'cltbld', 05:36:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:08 INFO - 'XPC_FLAGS': '0x0', 05:36:08 INFO - 'XPC_SERVICE_NAME': '0', 05:36:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:10 INFO - Downloading blobuploader-1.2.4.tar.gz 05:36:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:36:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:36:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:11 INFO - Downloading docopt-0.6.1.tar.gz 05:36:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:36:11 INFO - Installing collected packages: blobuploader, requests, docopt 05:36:11 INFO - Running setup.py install for blobuploader 05:36:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:36:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:36:11 INFO - Running setup.py install for requests 05:36:11 INFO - Running setup.py install for docopt 05:36:11 INFO - Successfully installed blobuploader requests docopt 05:36:11 INFO - Cleaning up... 05:36:12 INFO - Return code: 0 05:36:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:36:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:36:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:12 INFO - 'HOME': '/Users/cltbld', 05:36:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:12 INFO - 'LOGNAME': 'cltbld', 05:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:12 INFO - 'MOZ_NO_REMOTE': '1', 05:36:12 INFO - 'NO_EM_RESTART': '1', 05:36:12 INFO - 'PAGER': '/bin/cat', 05:36:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:12 INFO - 'PWD': '/builds/slave/test', 05:36:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:12 INFO - 'SHELL': '/bin/bash', 05:36:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:12 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:12 INFO - 'USER': 'cltbld', 05:36:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:12 INFO - 'XPC_FLAGS': '0x0', 05:36:12 INFO - 'XPC_SERVICE_NAME': '0', 05:36:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:36:12 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-nfegp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:36:12 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-j1zt2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:36:12 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-DaKuUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:36:12 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-1aMdMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:36:12 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-8TmrbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:36:12 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-RPI2BY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:36:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:36:12 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-GFkccv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-PEp5gP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-wIEWpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-pDZo09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-tOn0Ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ldgpN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-v6WZcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-LBw4oV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-5dGqZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-wsdH99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:36:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:36:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-UtHPn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:36:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:36:14 INFO - Running setup.py install for manifestparser 05:36:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:36:14 INFO - Running setup.py install for mozcrash 05:36:14 INFO - Running setup.py install for mozdebug 05:36:14 INFO - Running setup.py install for mozdevice 05:36:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:36:14 INFO - Running setup.py install for mozfile 05:36:14 INFO - Running setup.py install for mozhttpd 05:36:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:36:14 INFO - Running setup.py install for mozinfo 05:36:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Running setup.py install for mozInstall 05:36:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Running setup.py install for mozleak 05:36:15 INFO - Running setup.py install for mozlog 05:36:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Running setup.py install for moznetwork 05:36:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Running setup.py install for mozprocess 05:36:15 INFO - Running setup.py install for mozprofile 05:36:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:36:15 INFO - Running setup.py install for mozrunner 05:36:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:36:16 INFO - Running setup.py install for mozscreenshot 05:36:16 INFO - Running setup.py install for moztest 05:36:16 INFO - Running setup.py install for mozversion 05:36:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:36:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:36:16 INFO - Cleaning up... 05:36:16 INFO - Return code: 0 05:36:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:36:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:36:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:16 INFO - 'HOME': '/Users/cltbld', 05:36:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:16 INFO - 'LOGNAME': 'cltbld', 05:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:16 INFO - 'MOZ_NO_REMOTE': '1', 05:36:16 INFO - 'NO_EM_RESTART': '1', 05:36:16 INFO - 'PAGER': '/bin/cat', 05:36:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:16 INFO - 'PWD': '/builds/slave/test', 05:36:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:16 INFO - 'SHELL': '/bin/bash', 05:36:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:16 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:16 INFO - 'USER': 'cltbld', 05:36:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:16 INFO - 'XPC_FLAGS': '0x0', 05:36:16 INFO - 'XPC_SERVICE_NAME': '0', 05:36:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:36:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ls_5NX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:36:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:36:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:36:16 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-DmdKwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:36:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-CDJIGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:36:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-OxGzkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:36:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-SSUJ19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:36:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Dq5gF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:36:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ZJ95EP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:36:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-VDffbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:36:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Xv5Uua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:36:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-LMBBYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:36:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:36:17 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-GSDpKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:36:18 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-illESd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:36:18 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-mOqdzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:36:18 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Uzgvg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:18 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-hVuwcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:36:18 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Pb0k4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:36:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:36:18 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-e0w5oG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:36:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:20 INFO - Downloading blessings-1.6.tar.gz 05:36:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:36:20 INFO - Installing collected packages: blessings 05:36:20 INFO - Running setup.py install for blessings 05:36:20 INFO - Successfully installed blessings 05:36:20 INFO - Cleaning up... 05:36:20 INFO - Return code: 0 05:36:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:36:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:36:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:36:20 INFO - Reading from file tmpfile_stdout 05:36:20 INFO - Current package versions: 05:36:20 INFO - blessings == 1.6 05:36:20 INFO - blobuploader == 1.2.4 05:36:20 INFO - docopt == 0.6.1 05:36:20 INFO - functools32 == 3.2.3-2 05:36:20 INFO - jsonschema == 2.5.1 05:36:20 INFO - manifestparser == 1.1 05:36:20 INFO - mozInstall == 1.12 05:36:20 INFO - mozcrash == 1.0 05:36:20 INFO - mozdebug == 0.1 05:36:20 INFO - mozdevice == 0.51 05:36:20 INFO - mozfile == 1.2 05:36:20 INFO - mozhttpd == 0.7 05:36:20 INFO - mozinfo == 0.10 05:36:20 INFO - mozleak == 0.1 05:36:20 INFO - mozlog == 3.4 05:36:20 INFO - moznetwork == 0.27 05:36:20 INFO - mozprocess == 0.25 05:36:20 INFO - mozprofile == 0.28 05:36:20 INFO - mozrunner == 6.13 05:36:20 INFO - mozscreenshot == 0.1 05:36:20 INFO - mozsystemmonitor == 0.3 05:36:20 INFO - moztest == 0.8 05:36:20 INFO - mozversion == 1.4 05:36:20 INFO - psutil == 3.1.1 05:36:20 INFO - requests == 1.2.3 05:36:20 INFO - wsgiref == 0.1.2 05:36:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:36:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:36:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:20 INFO - 'HOME': '/Users/cltbld', 05:36:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:20 INFO - 'LOGNAME': 'cltbld', 05:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:20 INFO - 'MOZ_NO_REMOTE': '1', 05:36:20 INFO - 'NO_EM_RESTART': '1', 05:36:20 INFO - 'PAGER': '/bin/cat', 05:36:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:20 INFO - 'PWD': '/builds/slave/test', 05:36:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:20 INFO - 'SHELL': '/bin/bash', 05:36:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:20 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:20 INFO - 'USER': 'cltbld', 05:36:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:20 INFO - 'XPC_FLAGS': '0x0', 05:36:20 INFO - 'XPC_SERVICE_NAME': '0', 05:36:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:36:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:36:20 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:36:22 INFO - Downloading marionette_driver-1.4.0.tar.gz 05:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 05:36:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:36:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:36:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:36:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:36:22 INFO - Installing collected packages: marionette-driver 05:36:22 INFO - Running setup.py install for marionette-driver 05:36:22 INFO - Successfully installed marionette-driver 05:36:22 INFO - Cleaning up... 05:36:22 INFO - Return code: 0 05:36:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10314fc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103153768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c334da620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:36:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:36:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:36:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:22 INFO - 'HOME': '/Users/cltbld', 05:36:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:22 INFO - 'LOGNAME': 'cltbld', 05:36:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:22 INFO - 'MOZ_NO_REMOTE': '1', 05:36:22 INFO - 'NO_EM_RESTART': '1', 05:36:22 INFO - 'PAGER': '/bin/cat', 05:36:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:22 INFO - 'PWD': '/builds/slave/test', 05:36:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:22 INFO - 'SHELL': '/bin/bash', 05:36:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:22 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:22 INFO - 'USER': 'cltbld', 05:36:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:22 INFO - 'XPC_FLAGS': '0x0', 05:36:22 INFO - 'XPC_SERVICE_NAME': '0', 05:36:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:36:23 INFO - Cleaning up... 05:36:23 INFO - Return code: 0 05:36:23 INFO - Running post-action listener: _resource_record_post_action 05:36:23 INFO - Running post-action listener: _start_resource_monitoring 05:36:23 INFO - Starting resource monitoring. 05:36:23 INFO - [mozharness: 2017-06-09 12:36:23.056745Z] Finished create-virtualenv step (success) 05:36:23 INFO - [mozharness: 2017-06-09 12:36:23.058066Z] Running install step. 05:36:23 INFO - Running pre-action listener: _resource_record_pre_action 05:36:23 INFO - Running main action method: install 05:36:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:36:23 INFO - Reading from file tmpfile_stdout 05:36:23 INFO - Detecting whether we're running mozinstall >=1.0... 05:36:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:36:23 INFO - Reading from file tmpfile_stdout 05:36:23 INFO - Output received: 05:36:23 INFO - Usage: mozinstall [options] installer 05:36:23 INFO - Options: 05:36:23 INFO - -h, --help show this help message and exit 05:36:23 INFO - -d DEST, --destination=DEST 05:36:23 INFO - Directory to install application into. [default: 05:36:23 INFO - "/builds/slave/test"] 05:36:23 INFO - --app=APP Application being installed. [default: firefox] 05:36:23 INFO - mkdir: /builds/slave/test/build/application 05:36:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:36:44 INFO - Reading from file tmpfile_stdout 05:36:44 INFO - Output received: 05:36:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:36:44 INFO - Running post-action listener: _resource_record_post_action 05:36:44 INFO - [mozharness: 2017-06-09 12:36:44.972831Z] Finished install step (success) 05:36:44 INFO - [mozharness: 2017-06-09 12:36:44.972966Z] Running setup-mitmproxy step. 05:36:44 INFO - Running pre-action listener: _resource_record_pre_action 05:36:44 INFO - Running main action method: setup_mitmproxy 05:36:44 INFO - Skipping: mitmproxy is not required 05:36:44 INFO - Running post-action listener: _resource_record_post_action 05:36:44 INFO - [mozharness: 2017-06-09 12:36:44.973623Z] Finished setup-mitmproxy step (success) 05:36:44 INFO - [mozharness: 2017-06-09 12:36:44.973804Z] Running run-tests step. 05:36:44 INFO - Running pre-action listener: _resource_record_pre_action 05:36:44 INFO - Running main action method: run_tests 05:36:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:36:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:36:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:36:44 INFO - 2017-06-09 05:36:44.988 screenresolution[2648:295699] starting screenresolution argv=screenresolution get 05:36:44 INFO - 2017-06-09 05:36:44.991 screenresolution[2648:295699] Display 0: 1600x1200x32@60 05:36:44 INFO - 2017-06-09 05:36:44.997 screenresolution[2649:295701] starting screenresolution argv=screenresolution list 05:36:45 INFO - Available Modes on Display 0 05:36:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:36:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:36:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:36:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:36:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:36:45 INFO - Intel Iris: 05:36:45 INFO - Chipset Model: Intel Iris 05:36:45 INFO - Type: GPU 05:36:45 INFO - Bus: Built-In 05:36:45 INFO - VRAM (Dynamic, Max): 1536 MB 05:36:45 INFO - Vendor: Intel (0x8086) 05:36:45 INFO - Device ID: 0x0a2e 05:36:45 INFO - Revision ID: 0x0009 05:36:45 INFO - Displays: 05:36:45 INFO - Display: 05:36:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:36:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:36:45 INFO - Display Serial Number: 1600x1200 60 05:36:45 INFO - Main Display: Yes 05:36:45 INFO - Mirror: Off 05:36:45 INFO - Online: Yes 05:36:45 INFO - Rotation: Supported 05:36:45 INFO - Return code: 0 05:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:36:45 INFO - Python 2.7.3 05:36:45 INFO - Return code: 0 05:36:45 INFO - grabbing minidump binary from tooltool 05:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:36:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ea5d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e6fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e96be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:36:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:36:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:36:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:36:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:36:45 INFO - Return code: 0 05:36:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:36:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:36:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:36:45 INFO - ENV: RUST_BACKTRACE is now 1 05:36:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:36:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0221 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:36:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CJj1ErsY2o/Render', 05:36:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:45 INFO - 'HOME': '/Users/cltbld', 05:36:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:45 INFO - 'LOGNAME': 'cltbld', 05:36:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:36:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:45 INFO - 'MOZ_NO_REMOTE': '1', 05:36:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:36:45 INFO - 'NO_EM_RESTART': '1', 05:36:45 INFO - 'PAGER': '/bin/cat', 05:36:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:45 INFO - 'PWD': '/builds/slave/test', 05:36:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:36:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:45 INFO - 'RUST_BACKTRACE': '1', 05:36:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:36:45 INFO - 'SHELL': '/bin/bash', 05:36:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners', 05:36:45 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 05:36:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:45 INFO - 'USER': 'cltbld', 05:36:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:45 INFO - 'XPC_FLAGS': '0x0', 05:36:45 INFO - 'XPC_SERVICE_NAME': '0', 05:36:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:36:45 INFO - mozversion application_buildid: 20170609040235 05:36:45 INFO - mozversion application_changeset: 1742b1bdadd13a02df95ca690bea9cc42ff40c91 05:36:45 INFO - mozversion application_display_name: Nightly 05:36:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:36:45 INFO - mozversion application_name: Firefox 05:36:45 INFO - mozversion application_remotingname: firefox 05:36:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:36:45 INFO - mozversion application_vendor: Mozilla 05:36:45 INFO - mozversion application_version: 55.0a1 05:36:45 INFO - mozversion platform_buildid: 20170609040235 05:36:45 INFO - mozversion platform_changeset: 1742b1bdadd13a02df95ca690bea9cc42ff40c91 05:36:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:36:45 INFO - mozversion platform_version: 55.0a1 05:36:45 INFO - using testdate: 1497011805 05:36:45 INFO - actual date: 1497011805 05:36:45 INFO - starting webserver on 'localhost:55128' 05:36:45 INFO - SUITE-START | Running 2 tests 05:36:45 INFO - TEST-START | dromaeo_css 05:36:45 INFO - operating with platform_type : mac_ 05:36:45 INFO - Initialising browser for dromaeo_css test... 05:36:45 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:55128/getInfo.html -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpR1LRDc/profile 05:36:45 INFO - TEST-INFO | started process 2659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:55128/getInfo.html -foreground) 05:37:19 INFO - TEST-INFO | 2659: killed by SIGTERM 05:37:19 INFO - Browser initialized. 05:37:19 INFO - Running cycle 1/1 for dromaeo_css test... 05:37:19 INFO - TEST-INFO | started process 2667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpR1LRDc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:21 INFO - PID 2667 | 2017-06-09 05:37:21.491 plugin-container[2668:296198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 05:37:21 INFO - PID 2667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:37:21 INFO - PID 2667 | 2017-06-09 05:37:21.492 plugin-container[2668:296198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0f, name = 'com.apple.CFPasteboardClient' 05:37:21 INFO - PID 2667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:37:21 INFO - PID 2667 | 2017-06-09 05:37:21.492 plugin-container[2668:296198] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:37:22 INFO - PID 2667 | 2017-06-09 05:37:22.188 plugin-container[2669:296246] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 05:37:22 INFO - PID 2667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:37:22 INFO - PID 2667 | 2017-06-09 05:37:22.188 plugin-container[2669:296246] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 05:37:22 INFO - PID 2667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:37:22 INFO - PID 2667 | 2017-06-09 05:37:22.188 plugin-container[2669:296246] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:37:31 INFO - PID 2667 | RSS: Main: 254025728 05:37:31 INFO - PID 2667 | 05:40:33 INFO - PID 2667 | Cycle 1(1): loaded http://localhost:55128/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55128/tests/dromaeo/cssquery-ext.html) 05:40:35 INFO - PID 2667 | RSS: Main: 263389184 05:40:35 INFO - PID 2667 | 05:43:36 INFO - PID 2667 | Cycle 1(1): loaded http://localhost:55128/tests/dromaeo/cssquery-ext.html (next: http://localhost:55128/tests/dromaeo/cssquery-jquery.html) 05:43:37 INFO - PID 2667 | RSS: Main: 276553728 05:43:37 INFO - PID 2667 | 05:46:39 INFO - PID 2667 | Cycle 1(1): loaded http://localhost:55128/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55128/tests/dromaeo/cssquery-mootools.html) 05:46:40 INFO - PID 2667 | RSS: Main: 276455424 05:46:40 INFO - PID 2667 | 05:49:41 INFO - PID 2667 | Cycle 1(1): loaded http://localhost:55128/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55128/tests/dromaeo/cssquery-prototype.html) 05:49:42 INFO - PID 2667 | RSS: Main: 279367680 05:49:42 INFO - PID 2667 | 05:52:45 INFO - PID 2667 | Cycle 1(1): loaded http://localhost:55128/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55128/tests/dromaeo/cssquery-yui.html) 05:52:46 INFO - PID 2667 | RSS: Main: 278376448 05:52:46 INFO - PID 2667 | 05:55:48 INFO - PID 2667 | Cycle 1(1): loaded http://localhost:55128/tests/dromaeo/cssquery-yui.html (next: http://localhost:55128/tests/dromaeo/cssquery-dojo.html) 05:55:48 INFO - PID 2667 | RSS: Main: 279121920 05:55:48 INFO - PID 2667 | 05:55:48 INFO - PID 2667 | __start_tp_report 05:55:48 INFO - PID 2667 | _x_x_mozilla_page_load 05:55:48 INFO - PID 2667 | _x_x_mozilla_page_load_details 05:55:48 INFO - PID 2667 | |i|pagename|runs| 05:55:48 INFO - PID 2667 | |0;dojo.html;3543.822808859557;3545.911352216196;3569.2326149877754;3605.31499015187;3617.728670349723;4770.880727981802;5176.611754118438;5329.880725047739;6027.336992930785;6184.752609895604;6062.120992456091;6129.297663865974;6133.723982420791;6142.375998161084;6216.782412615559;4495.617872480843;5260.210968354739;5414.269073675053;5414.8917021659545;5424.457554244564;5416.241726158341;5499.890002199953;5506.256655351536;5506.366767821712;5509.063459211923;5271.446498117719;5349.170878513836;5425.565954723613;5452.1276595744885;5482.5613950883835;4471.2845944649025;5397.946020539823;5403.594730395197;5431.864203394907;5472.753726082336;4435.268180750172;5347.25138480613;5370.22131790892;5381.811636592701;5416.350037995427;5717.171010203498;5926.940730592682;6158;6164.321924588292;6180.81457556274;6319.778807741753;6330.936690633081;6339.239291285029;6340.144080549159;6393.680315984182;6727.66361681914;6730.798076057726;6742.157230346207;6750.189977202767;6766.458683305373;6274.717637706314;6310.810675679737;6317.5893566918;6324.3043265240785;6331.050342448623;6614.702338394687;6719.428848547928;6764.086848275519;6821.113255276782;6837;6324.430801227912;6358.23701155855;6529.2817790043055;6540.803775886731;6587.473002159816;6512.739490420425;6661.16735408074;6666;6685.498587605949;6702.329767023259;6611.305812889674;6613.107230523914;6619.304972977864;6623.569467984566;6705.597664140168;6365.427111559982;6415.26224484181;6510.837229069311;6551.770688025897;6639.601623902581;5315.441878602768;5317.8404647860625;5336.226247194135;5341.305630268041;5361.115415956324;4736.8578942631775;4749.833755818529;4757.738324392191;4758.595519380891;4759.286107083896;5952.404759524013;6071.241094863142;6301.243850737941;6318.557700960981;6356.046593011085;6743.15710536183;6767.458603311723;6770.424513916372;6772.627505487147;6788.2193547742645;6335.556511044183;6350.745970161234;6355.3009168991375;6361.459275961595;6364.268109167412;5751.626144300607;5754.223179870748;5822.592418530747;5861.208736820475;5926.940730592725;5790.478856902899;6875.656217189121;6901.551399159039;6923.030775691508;6936.895946560705;6748.392644662004;6758.86482270342;6762.594244345355;6766.458683305422;6789.626570538668;6642.070110184674;6719.563228390139;6875.278095799869;6919.446444284546;6922.619255940972;6519.445847102859;6541.443977261986;6602.438792702674;6613.272540020497;6614.4708423325055;6718.966405167943;6725.092112564741;6726.461883049246;6736.831579210559;6738.191417029988;5769.1634712967225;6235.594686345373;6298.685065746603;6455;6517.185351831021;6457.096006558992;6490.643014634241;6494.74021039172;6494.935050649433;6511.088447617242;6280;6362.554621176473;6371.55399122057;6395.264544577247;6396.712147953446;6316.873662526816;6532.5753826001155;6533.444657204001;6535.901961470677;6539.803805885831;6275.435210831047;6283.214598175228;6302.590331628429;6372.012338087604;6473.935260647333 05:55:48 INFO - PID 2667 | |1;ext.html;672.9327067293272;785.2932360875216;850.0691742541917;1247.432418249696;1300.9544665936683;32020.519692204634;39278.21443571127;39342.803285983566;39343.40984885229;39495.60504394955;39438.802805986;39747;39755;39782.60217397825;39861.40207896884;7710.845783084335;7803.141654418009;7807.024121984752;8278.461899976512;8494.74515764528;8380.287675547555;8474.74575762728;8475.110113438093;8480.024797148346;8488.490690558556;8435.283000944926;8499.065102838682;8505.319574434061;8512.404131710782;8520;8434.915650843473;8483.363747718946;8486.024107227686;8486.702965396182;8487.618057187441;8339.874901876477;8380.706675266336;8398.664053437917;8408.495490270603;8429.704960326359;37075;37190.8140459296;37201.88394348174;37219.25561488782;37363.813180934194;3498.912527186815;3521.0845180253064;3539.610642829287;3544.5923718772415;3544.8404821782824;30711.846440767877;30764.846175768977;30765.077047688606;30821.691783082108;30844.691553084405;3029.606151200352;3037.271054946797;3051.755859531232;3084.552739852731;3089.7837151399413;239541;243101;251603;291529;292297;47236.05527889491;47406;47555;47559.76220118877;47567.04865902662;50106.74946625317;50149;50193.99612007739;50250.246246306335;50393.74803125961;14117.294135293194;14140.292985350898;14176;14239.50161744334;14311.92844035794;11548.884511154782;11574.768504629861;11578.71053223676;11581.363025033535;11590.072794176445;11419.714507137222;13704.52034178799;13751.587452376445;13755.724885502434;13820.654483637989;5973.014452615356;6021.157038014681;6032.849178770478;6070.362611925684;6075.5747097703625;969.9466529340954;992.965246216379;1002.6340385759291;1044.3785947361307;1048.1876545677162;23134.305970820904;28467.857660711565;29899;30286.485675716125;31110;1026.8435065093583;1053.1153830782027;1075.3924032921375;1121.2039451989178;1121.5681962444517;11936.403179840972;12000.279983201035;12365;12408.069394795426;12487;319785;321086;321851;322077;322579;39673.20653586912;48310.03379932381;48582.51417485851;48633.78415539569;48662.02675946532;1342.3892129081244;1426.572028391499;1488.5087920986266;1493.9477118301024;1500.3848422146868;22489.212877549206;22539;23802.52394952126;23815.404614884766;23843.523129536967;37995.620043800314;38314.42528362133;38387;38515.807420963836;38564.42153367758;280995;281102;282090;282202;287578.1242187551;28372.43255134927;28953.85523072371;31850.362992739552;33765.32469350648;33869;39014;39213.01967450837;40308.798456007535;40650.593494064684;41051;27583.586246205923;28492.145235642965;28737;28742.856285719274;29830.55254171146;28158.155255341553;28326;28393.716062839932;29537;29886.850565747904;34752.957411277705;34978.825105874304;35133.47299790556;35158.64841351554;35276.29447411089;28391.00631477971;28509.429811404072;28518.57222141628;29465;29923.401531969674;22903.312900613008;23537.764622353556;23561;23617.88191059034;23623.88188059049 05:55:48 INFO - PID 2667 | |2;jquery.html;463.25184826505193;475.1660835234164;491.66842034780296;513.9948600513995;532.7149974763497;21315.040823162955;21598.676019859715;22485.875706214665;22518;24245.51508969824;23591.528169436642;24119.758802411972;24241.63637545438;24284.27147185587;24391.390215244628;6414.871702565958;6525.086487891699;6541.607503549779;6542.803715888531;6544.836379090537;5986.371430999748;6405.679716014204;6447.484201263887;6454.419102280794;6521.706523206443;6212.1303017577575;6253.937460625404;6260.7808726694575;6261.99232491206;6266.404691554295;6247.531435142359;6251.531135164858;6258.06129080637;6260.186175797163;6268.091126786636;5742.2247996520355;5742.827715168552;5750.07998720207;5750.223719797816;5759.798407055733;24700.505989880283;25242;29156.562651560245;29749.553756693665;29774;2187.6499760038478;2230.6654001899683;2231.5871563760757;2236.1726161320253;2240.4958884251078;18595.163217655238;19149.234030638756;19154.904225478782;19168.424947251606;19171.32900348495;1989.5523507210908;2016.5765189310116;2209.8784566848817;2239.8768067756428;2254.8872556372116;191805.04097479425;197076;200027;200817.99182008285;203418;12512.186707863959;15028.919071590297;15487.070775753491;15672.294746736425;15797;20262;20266.392008239778;20288.289909853083;20321.49196270105;20322.288719894732;9708.708738737849;9926.156276716416;9932.553035113437;9936.850947235796;9944.950275248577;9300.627974880928;9312.627494900127;9315.72052838416;9356.672516461891;9387.34288599805;8986.86519702205;9070.909290907006;9126.908730912739;9142.040085790895;9142.405743626643;2386.8781672613845;2466;2592.831465954707;2625.868706564664;2740.369714965569;828.6395417993192;900.8603666431713;931.2549960031947;969.8593703912893;990.4700984973005;21063.262785802424;21082.945852707304;21860;21860.344189674335;22259.332220033102;967.4630580028012;985.9014098590084;997.7876879591187;1022.8823685276288;1026.6201505443034;10621.468926553796;10881.455927203766;11214.607488737855;11329.943350283196;11385.886141138648;549840.2539936533;570379;586594;594971;596912;150712;152550;152672.70990935146;152892;156767.4323256753;992.335135459244;1044.253358848427;1133.3823066428815;1163.1857699610307;1200.1538915065032;22340.21809236669;23298.53402931898;24157.15449959243;24377.63433548463;24453;68643;70753.29246707672;70805.29194708193;72597.54804903977;73731;97645;98155.01844981458;98255;98593.50703246437;98810;11296.661100166679;11804.409779511161;12039;12044.819327710267;12114.21257618252;18661.253549858397;18698.158582863533;18844.717329240346;18957.862528248348;18986.240550377832;12377.319247441477;12388.070894683115;12540.811887821508;12555.12114152037;12687;13126.803097953347;13190.868091319348;13219.47122115143;13226.338683066;13241.3379331035;14455.49405770835;15277.00699454532;15612.609684757972;15682.921585392456;15813.288402021706;11690.4739286734;11742.354170520703;11863.228890122115;11873.346965916957;11895.821562676394;14983.7003259932;15093.773593395888;15095.924520377328;15101.32044057998;15110.39558417695 05:55:48 INFO - PID 2667 | |3;mootools.html;1373;1433.6702558411569;1436.3105709259548;1446.3925151436401;1453.6147920800984;13077.149985250951;13196.53812116576;13703.177809331448;13738.587842364721;13983.440662373492;13513.729725405487;13745.587632371045;13755.105918115316;13761.862381376182;13794.862051379483;2679.115891755726;2723.332783468053;2730.2218867622732;2731.754142127208;2742.3966727320058;2248.651459023854;2342.0982921575232;2633.367991681992;2655.7609815116634;2682.7317268273214;2693.6902256240587;2741.191348552172;2742.9862850685818;2743.27303264635;2749.8900043998215;2325.802306803924;2670.212287375219;2697.150397624752;2701.486717523684;2710.0785732850927;2675.304420850593;2679.397135644484;2689.515887140314;2691.380982374065;2695.380062585616;13223.074384793068;13296.933515332457;13334.666633334147;13348.86651133486;13372.264525451097;7213.242609526029;7619.4285428593075;7628.923710762877;7638.5798781067015;7656.196099409593;4544.590986811169;4688.249880019214;4721.976390118062;4777.5222477752295;4827.782749776268;1372.5196181336453;1378.827646544182;1382.7234553089422;1409.4784929576122;1410.2173293821925;19582.90208548948;19680.70478942817;19699.11353989074;19763.802361976483;19768.604627907505;6491.935080649133;6492.156019717407;6507.219133703986;6553.2136143663065;6610.86778264432;14130.646733831736;14171.433142674383;14182.716345673236;14188.432462701376;14211;6390.233172019387;7221.530600511055;7342;7343.926560734324;7355.117385913724;6576.671166441659;6718.966405167943;6758.628275444799;6762.594244345257;6763.93236067633;4924.4090709115135;5039.571636410946;5045.369328833895;5047.87380315495;5065.949340506621;1303.6154334471353;1348.447136673959;1412.329143656755;1441.4234306277363;1442.819647544057;818.1818181818181;833.9874901876356;856.1310270075878;856.208007592974;893.883795106632;7541.962290188624;7702.537847729154;7835.725749598737;7954.443188976832;8184.7953801155445;940.3135710931059;941.091846368258;954.494118117385;963.8698775665335;981.6122631560494;5261.552768014762;5330.680159190461;5415.214793854869;5475.151351540518;5574.498295153456;17299.827001730075;17796.021218833088;18124.45626631203;18128.637427251644;18217.635647287247;17861.464156075337;17910.820891790914;17957;18038.27846886136;18046.368377106737;9022.86465703015;9302.25581953442;9330.720078397659;9529.237660987104;9549.188318992825;7809;7933.8413231736195;8245.216704413071;8472.915270847212;8477.152284771473;15765.369385224463;15987.520374388787;16197;16398.098104603883;16591.751123732913;16410.83589164093;16463.91768041152;16595.91702041482;16771.748423773897;17023;10393.37639741618;10853.240273180802;10957.397343146204;10974.615888443866;11016.11871050298;12419.75160496803;12524.06069544806;12525.185862918881;12540.435680394221;12545.435455404346;9786.951065244628;9806.460644664612;9832.065953734384;9974.850377244202;10168.034036766496;9749.756256093655;9810.607575696893;9882.4564648945;9975.351602145838;9997.050280223368;9100.271978241592;9313.813723725623;9327.253819694544;9345.439273643604;9347.252219822534;6530.869382612416;6613.371729685672;7026.402755765817;7038.40173585251;7097.219305876246;6917.132374258821;6939.826504337432;6948.583085014915;7067.399271062018;7239.239879812754 05:55:48 INFO - PID 2667 | |4;prototype.html;442.8162312640254;463.756527822893;467.91577516047096;468.34431795486313;479.697790392053;5655.123455864347;5747.683877386742;6518.250401203863;6567.868642627144;6590.242122155953;6176.629402235858;6308.243010838694;6412.166418365618;6420.807375778734;6430.5242481644555;4341.153475072362;4557.794899229542;4801.447833499157;4819.397575303087;4831.178699621055;4272.803614987808;4606.216943119678;4789.808407663707;4800.735959522242;4819.325294458778;3679;4201.369794530814;4370.672199585044;4760.809567617292;4796.90406191876;4802.375691160162;4815.374001379833;4832.637552183583;4833.540813622701;4851.830185943492;4204.894877628053;4210.810513526899;4214.3889136075095;4218.860907554974;4244.384564238168;5541.750621222014;6338.366163383671;6367.458766004896;6384.7126879290545;6393.680315984182;5724.971375143139;5731.369549349568;5746.6325592549965;5763.221965034709;5784.929787989236;6963.40811031063;6970.581765094111;6987.475939304572;6988.056612357317;6990.825229369256;4807.759612019385;4812.25410061441;4815.277708343741;4824.5044036348845;4847.709137451764;6224.626522408625;6253.78111766086;6966.860662786767;7220.494565380425;7306.2693730626515;4760.09558183944;4842.661013729005;4924.3105965164905;4937.259411088294;4960.082384758831;3919.6668283195677;4508.909821803545;4617.3304870793545;5047.924281135786;5051.87370315745;5500.092484739973;5546.473085056913;5641.37675980671;5668.8866222675315;5734.16854556087;5531.612787104864;6887.965560172167;6971.829930511432;6973.930260697328;6977.302269773084;1646.5965838369436;1702.880798344104;1715.6311393050555;1718.3384397007242;1723.6983527882571;1110.7833972375308;1137.8791889988354;1157.8726340102583;1157.9826302605468;1159.2291126400848;5678.716064196774;5968.76124954997;6684.030815531721;6728.293529179464;6734.865302693918;6427.903581446189;7202.351788339075;7227.096612923385;7234.819129521804;7312.1956584775635;6361.61830290184;6387.457066149336;6468.482521398277;6579.967100164565;6608.702608382634;3473.4095203815414;3518.7360947928496;3735.364987952055;3761.887143385703;3788.6400791924725;6846.691898864563;7426.509540515949;7427.442941779388;7429.44279179053;7469.887951680729;6839.555428897106;7367.447441441914;7378;7431.51695139825;7437.367823734935;5981.910271346021;7409.259074092655;7442.96278518604;7475.850482990419;7513.867372944967;6158.27568280882;6608.240052394036;6620.775156596045;7175.210726819942;7225.710971561289;6214.844628884133;6569.868602627825;7059.647017648994;7073.929260707327;7135.678894449658;5580.9162862557905;7155.141383034069;7173.852183650591;7456;7482.2517748224745;4880.145974454385;4903.975480122576;4915.26271059344;4922.212446008622;4928.654994150375;5810;6313.463355614824;6965.79102626922;7092.9645351772915;7123.679434425359;6877.690503927235;6920.342567456285;6923.653817309215;6936.618485983319;7006.75476358325;6843.213030501556;6880.724771009103;6892.724291028303;6901.378875901193;7020.333068358498;6771.307173206698;6787.457003439614;6826.624535650587;6829.214640316229;6832.829179270558;5572.526335261386;6862.245153033262;6895.207051188977;6902;6904.827379315558;6392.4246817786625;6727.798166055026;6735.4274886635185;6737.326267373223;6761.898571521333 05:55:48 INFO - PID 2667 | |5;yui.html;783.2480818414322;825.4057078903188;842.3050982939073;868.0971789339088;875.7985663297442;13851.653708657288;15056.6988660227;15403.92172547922;16398.83601163988;16946.576335591617;13938.303084845767;17038.914805425986;17724.29102835885;17763.28946842128;17792.733109003377;980.2207245240033;1000.1536410449598;1003.8845532763717;1006.2050979726008;1010.2019404670301;1013.6502906497284;1035.8135535603587;1038.1435315864442;1040.230229630072;1047.638564695179;957.3059531839431;1076.43487169236;1077.062955228952;1079.4818487126183;1080.8702955645333;1059.7562560611025;1066.53605681528;1067.0716476665311;1067.2795862792584;1068.3643232276784;770.1528318849277;781.9061712594469;785.4855069929206;790.2295262119387;792.6551949901753;15049.849501505063;16762.497125086265;17311.653766924715;17325.393611223546;17569.121543922753;17101.657966840718;17185.656286874193;17575.384861529914;17696.469105926844;17711.202995865217;14426.495072672511;14749.483768068174;14765.557033289018;14769.852301477062;14800.629984250372;3574.857005719794;3588.820558972067;3600.369935261345;3600.4059330210484;3601.1897323102353;138549;138746;139773.60226397708;140903;141745.291273544;12518.561850335194;13411.798823017663;13912.791308130387;14038.157710537196;14157.575272741835;26035.869820650776;26544.805483753276;26855.597166042524;26871.328216794736;27137.864310678717;7238.276172382825;7279.2720727927845;7354.558726476428;7404.777856664308;7423.146338171179;3080.522519009557;3505.2463720299747;3783.1677031053673;3958.802059897051;3960.9801950990636;4531.43357080365;4623.16782979067;4639.652026098056;4648.651351148677;4649.000464900063;988.7330420786348;1009.8232809258351;1022.2077889635443;1030.5826140413144;1033.2973577966904;634.5494698763837;641.2909595926895;657.4641667041346;687.5805758487319;688.8691148681835;11129.944350278358;12597.370131493388;12689.36553172356;12932.741345173044;13323.267220302783;603.3571666029945;626.5770604841714;659.6668682315496;681.4241965539112;684.6405637040593;4682;5358.517733404013;5624.465675760796;5635.69003704792;5903.3801450847905;105533;115106.4244678788;117383;126802;134352.32823835817;73325.6333718328;103393.96606033994;109214;110589.89410105957;113876;95272;97902.5104874471;98606.50696746471;101512;103168.96831031743;11646.650600481998;12047.397630118634;12390.380480976095;12480.5007799687;12752.808707869575;43380.13239735251;44515.77742111269;44561.55438445574;45069.774651126536;45588.404405845635;57590.7120464395;58333.91662583579;58665.70667146637;59566.106508403274;60610.69694651499;17506.387276445264;17561.297548097933;17580.73628895593;17886.5528361792;17972.55068623295;15039;16577.585560361087;16817.990920544806;17212.741808872626;18587.628247435245;15015.324310406277;17585.736213957058;17674.5581360467;18074.819251807312;18266.72599911029;17251;17260.568485787957;18273.634527309645;18319.267229310677;18350.541236469195;17526.474205773848;17783.644327113125;17862.19620117072;17975.820241797937;18162.364317248834;11418.885811141783;11535.192536522363;11694.47374868116;12216.511339546674;12237.449314781034;13281.40233689467;14146.858531414555;14211.64470888236;14429.711405771615;14455.060421072596 05:55:48 INFO - PID 2667 | __end_tp_report 05:55:48 INFO - PID 2667 | __start_cc_report 05:55:48 INFO - PID 2667 | _x_x_mozilla_cycle_collect,456 05:55:48 INFO - PID 2667 | __end_cc_report 05:55:48 INFO - PID 2667 | __startTimestamp1497012948321__endTimestamp 05:55:48 INFO - PID 2667 | 05:55:48 INFO - PID 2667 | ------- Summary: start ------- 05:55:48 INFO - PID 2667 | Number of tests: 6 05:55:48 INFO - PID 2667 | 05:55:48 INFO - PID 2667 | [#0] dojo.html Cycles:165 Average:6087.66 Median:6339.24 stddev:746.75 (11.8%) stddev-sans-first:721.88 05:55:48 INFO - PID 2667 | Values: 3543.8 3545.9 3569.2 3605.3 3617.7 4770.9 5176.6 5329.9 6027.3 6184.8 6062.1 6129.3 6133.7 6142.4 6216.8 4495.6 5260.2 5414.3 5414.9 5424.5 5416.2 5499.9 5506.3 5506.4 5509.1 5271.4 5349.2 5425.6 5452.1 5482.6 4471.3 5397.9 5403.6 5431.9 5472.8 4435.3 5347.3 5370.2 5381.8 5416.4 5717.2 5926.9 6158.0 6164.3 6180.8 6319.8 6330.9 6339.2 6340.1 6393.7 6727.7 6730.8 6742.2 6750.2 6766.5 6274.7 6310.8 6317.6 6324.3 6331.1 6614.7 6719.4 6764.1 6821.1 6837.0 6324.4 6358.2 6529.3 6540.8 6587.5 6512.7 6661.2 6666.0 6685.5 6702.3 6611.3 6613.1 6619.3 6623.6 6705.6 6365.4 6415.3 6510.8 6551.8 6639.6 5315.4 5317.8 5336.2 5341.3 5361.1 4736.9 4749.8 4757.7 4758.6 4759.3 5952.4 6071.2 6301.2 6318.6 6356.0 6743.2 6767.5 6770.4 6772.6 6788.2 6335.6 6350.7 6355.3 6361.5 6364.3 5751.6 5754.2 5822.6 5861.2 5926.9 5790.5 6875.7 6901.6 6923.0 6936.9 6748.4 6758.9 6762.6 6766.5 6789.6 6642.1 6719.6 6875.3 6919.4 6922.6 6519.4 6541.4 6602.4 6613.3 6614.5 6719.0 6725.1 6726.5 6736.8 6738.2 5769.2 6235.6 6298.7 6455.0 6517.2 6457.1 6490.6 6494.7 6494.9 6511.1 6280.0 6362.6 6371.6 6395.3 6396.7 6316.9 6532.6 6533.4 6535.9 6539.8 6275.4 6283.2 6302.6 6372.0 6473.9 05:55:48 INFO - PID 2667 | 05:55:48 INFO - PID 2667 | [#1] ext.html Cycles:180 Average:43746.45 Median:321468.50 stddev:76147.81 (23.7%) stddev-sans-first:76292.75 05:55:48 INFO - PID 2667 | Values: 672.9 785.3 850.1 1247.4 1301.0 32020.5 39278.2 39342.8 39343.4 39495.6 39438.8 39747.0 39755.0 39782.6 39861.4 7710.8 7803.1 7807.0 8278.5 8494.7 8380.3 8474.7 8475.1 8480.0 8488.5 8435.3 8499.1 8505.3 8512.4 8520.0 8434.9 8483.4 8486.0 8486.7 8487.6 8339.9 8380.7 8398.7 8408.5 8429.7 37075.0 37190.8 37201.9 37219.3 37363.8 3498.9 3521.1 3539.6 3544.6 3544.8 30711.8 30764.8 30765.1 30821.7 30844.7 3029.6 3037.3 3051.8 3084.6 3089.8 239541.0 243101.0 251603.0 291529.0 292297.0 47236.1 47406.0 47555.0 47559.8 47567.0 50106.7 50149.0 50194.0 50250.2 50393.7 14117.3 14140.3 14176.0 14239.5 14311.9 11548.9 11574.8 11578.7 11581.4 11590.1 11419.7 13704.5 13751.6 13755.7 13820.7 5973.0 6021.2 6032.8 6070.4 6075.6 969.9 993.0 1002.6 1044.4 1048.2 23134.3 28467.9 29899.0 30286.5 31110.0 1026.8 1053.1 1075.4 1121.2 1121.6 11936.4 12000.3 12365.0 12408.1 12487.0 319785.0 321086.0 321851.0 322077.0 322579.0 39673.2 48310.0 48582.5 48633.8 48662.0 1342.4 1426.6 1488.5 1493.9 1500.4 22489.2 22539.0 23802.5 23815.4 23843.5 37995.6 38314.4 38387.0 38515.8 38564.4 280995.0 281102.0 282090.0 282202.0 287578.1 28372.4 28953.9 31850.4 33765.3 33869.0 39014.0 39213.0 40308.8 40650.6 41051.0 27583.6 28492.1 28737.0 28742.9 29830.6 28158.2 28326.0 28393.7 29537.0 29886.9 34753.0 34978.8 35133.5 35158.6 35276.3 28391.0 28509.4 28518.6 29465.0 29923.4 22903.3 23537.8 23561.0 23617.9 23623.9 05:55:48 INFO - PID 2667 | 05:55:48 INFO - PID 2667 | [#2] jquery.html Cycles:180 Average:40405.86 Median:12776.71 stddev:100449.38 (786.2%) stddev-sans-first:100686.40 05:55:48 INFO - PID 2667 | Values: 463.3 475.2 491.7 514.0 532.7 21315.0 21598.7 22485.9 22518.0 24245.5 23591.5 24119.8 24241.6 24284.3 24391.4 6414.9 6525.1 6541.6 6542.8 6544.8 5986.4 6405.7 6447.5 6454.4 6521.7 6212.1 6253.9 6260.8 6262.0 6266.4 6247.5 6251.5 6258.1 6260.2 6268.1 5742.2 5742.8 5750.1 5750.2 5759.8 24700.5 25242.0 29156.6 29749.6 29774.0 2187.6 2230.7 2231.6 2236.2 2240.5 18595.2 19149.2 19154.9 19168.4 19171.3 1989.6 2016.6 2209.9 2239.9 2254.9 191805.0 197076.0 200027.0 200818.0 203418.0 12512.2 15028.9 15487.1 15672.3 15797.0 20262.0 20266.4 20288.3 20321.5 20322.3 9708.7 9926.2 9932.6 9936.9 9945.0 9300.6 9312.6 9315.7 9356.7 9387.3 8986.9 9070.9 9126.9 9142.0 9142.4 2386.9 2466.0 2592.8 2625.9 2740.4 828.6 900.9 931.3 969.9 990.5 21063.3 21082.9 21860.0 21860.3 22259.3 967.5 985.9 997.8 1022.9 1026.6 10621.5 10881.5 11214.6 11329.9 11385.9 549840.3 570379.0 586594.0 594971.0 596912.0 150712.0 152550.0 152672.7 152892.0 156767.4 992.3 1044.3 1133.4 1163.2 1200.2 22340.2 23298.5 24157.2 24377.6 24453.0 68643.0 70753.3 70805.3 72597.5 73731.0 97645.0 98155.0 98255.0 98593.5 98810.0 11296.7 11804.4 12039.0 12044.8 12114.2 18661.3 18698.2 18844.7 18957.9 18986.2 12377.3 12388.1 12540.8 12555.1 12687.0 13126.8 13190.9 13219.5 13226.3 13241.3 14455.5 15277.0 15612.6 15682.9 15813.3 11690.5 11742.4 11863.2 11873.3 11895.8 14983.7 15093.8 15095.9 15101.3 15110.4 05:55:48 INFO - PID 2667 | 05:55:48 INFO - PID 2667 | [#3] mootools.html Cycles:180 Average:8256.31 Median:2736.47 stddev:5419.16 (198.0%) stddev-sans-first:5409.68 05:55:48 INFO - PID 2667 | Values: 1373.0 1433.7 1436.3 1446.4 1453.6 13077.1 13196.5 13703.2 13738.6 13983.4 13513.7 13745.6 13755.1 13761.9 13794.9 2679.1 2723.3 2730.2 2731.8 2742.4 2248.7 2342.1 2633.4 2655.8 2682.7 2693.7 2741.2 2743.0 2743.3 2749.9 2325.8 2670.2 2697.2 2701.5 2710.1 2675.3 2679.4 2689.5 2691.4 2695.4 13223.1 13296.9 13334.7 13348.9 13372.3 7213.2 7619.4 7628.9 7638.6 7656.2 4544.6 4688.2 4722.0 4777.5 4827.8 1372.5 1378.8 1382.7 1409.5 1410.2 19582.9 19680.7 19699.1 19763.8 19768.6 6491.9 6492.2 6507.2 6553.2 6610.9 14130.6 14171.4 14182.7 14188.4 14211.0 6390.2 7221.5 7342.0 7343.9 7355.1 6576.7 6719.0 6758.6 6762.6 6763.9 4924.4 5039.6 5045.4 5047.9 5065.9 1303.6 1348.4 1412.3 1441.4 1442.8 818.2 834.0 856.1 856.2 893.9 7542.0 7702.5 7835.7 7954.4 8184.8 940.3 941.1 954.5 963.9 981.6 5261.6 5330.7 5415.2 5475.2 5574.5 17299.8 17796.0 18124.5 18128.6 18217.6 17861.5 17910.8 17957.0 18038.3 18046.4 9022.9 9302.3 9330.7 9529.2 9549.2 7809.0 7933.8 8245.2 8472.9 8477.2 15765.4 15987.5 16197.0 16398.1 16591.8 16410.8 16463.9 16595.9 16771.7 17023.0 10393.4 10853.2 10957.4 10974.6 11016.1 12419.8 12524.1 12525.2 12540.4 12545.4 9787.0 9806.5 9832.1 9974.9 10168.0 9749.8 9810.6 9882.5 9975.4 9997.1 9100.3 9313.8 9327.3 9345.4 9347.3 6530.9 6613.4 7026.4 7038.4 7097.2 6917.1 6939.8 6948.6 7067.4 7239.2 05:55:48 INFO - PID 2667 | 05:55:48 INFO - PID 2667 | [#4] prototype.html Cycles:180 Average:5621.50 Median:6310.85 stddev:1733.02 (27.5%) stddev-sans-first:1693.73 05:55:48 INFO - PID 2667 | Values: 442.8 463.8 467.9 468.3 479.7 5655.1 5747.7 6518.3 6567.9 6590.2 6176.6 6308.2 6412.2 6420.8 6430.5 4341.2 4557.8 4801.4 4819.4 4831.2 4272.8 4606.2 4789.8 4800.7 4819.3 3679.0 4201.4 4370.7 4760.8 4796.9 4802.4 4815.4 4832.6 4833.5 4851.8 4204.9 4210.8 4214.4 4218.9 4244.4 5541.8 6338.4 6367.5 6384.7 6393.7 5725.0 5731.4 5746.6 5763.2 5784.9 6963.4 6970.6 6987.5 6988.1 6990.8 4807.8 4812.3 4815.3 4824.5 4847.7 6224.6 6253.8 6966.9 7220.5 7306.3 4760.1 4842.7 4924.3 4937.3 4960.1 3919.7 4508.9 4617.3 5047.9 5051.9 5500.1 5546.5 5641.4 5668.9 5734.2 5531.6 6888.0 6971.8 6973.9 6977.3 1646.6 1702.9 1715.6 1718.3 1723.7 1110.8 1137.9 1157.9 1158.0 1159.2 5678.7 5968.8 6684.0 6728.3 6734.9 6427.9 7202.4 7227.1 7234.8 7312.2 6361.6 6387.5 6468.5 6580.0 6608.7 3473.4 3518.7 3735.4 3761.9 3788.6 6846.7 7426.5 7427.4 7429.4 7469.9 6839.6 7367.4 7378.0 7431.5 7437.4 5981.9 7409.3 7443.0 7475.9 7513.9 6158.3 6608.2 6620.8 7175.2 7225.7 6214.8 6569.9 7059.6 7073.9 7135.7 5580.9 7155.1 7173.9 7456.0 7482.3 4880.1 4904.0 4915.3 4922.2 4928.7 5810.0 6313.5 6965.8 7093.0 7123.7 6877.7 6920.3 6923.7 6936.6 7006.8 6843.2 6880.7 6892.7 6901.4 7020.3 6771.3 6787.5 6826.6 6829.2 6832.8 5572.5 6862.2 6895.2 6902.0 6904.8 6392.4 6727.8 6735.4 6737.3 6761.9 05:55:48 INFO - PID 2667 | 05:55:48 INFO - PID 2667 | [#5] yui.html Cycles:180 Average:23818.74 Median:17565.21 stddev:35117.68 (199.9%) stddev-sans-first:35173.60 05:55:48 INFO - PID 2667 | Values: 783.2 825.4 842.3 868.1 875.8 13851.7 15056.7 15403.9 16398.8 16946.6 13938.3 17038.9 17724.3 17763.3 17792.7 980.2 1000.2 1003.9 1006.2 1010.2 1013.7 1035.8 1038.1 1040.2 1047.6 957.3 1076.4 1077.1 1079.5 1080.9 1059.8 1066.5 1067.1 1067.3 1068.4 770.2 781.9 785.5 790.2 792.7 15049.8 16762.5 17311.7 17325.4 17569.1 17101.7 17185.7 17575.4 17696.5 17711.2 14426.5 14749.5 14765.6 14769.9 14800.6 3574.9 3588.8 3600.4 3600.4 3601.2 138549.0 138746.0 139773.6 140903.0 141745.3 12518.6 13411.8 13912.8 14038.2 14157.6 26035.9 26544.8 26855.6 26871.3 27137.9 7238.3 7279.3 7354.6 7404.8 7423.1 3080.5 3505.2 3783.2 3958.8 3961.0 4531.4 4623.2 4639.7 4648.7 4649.0 988.7 1009.8 1022.2 1030.6 1033.3 634.5 641.3 657.5 687.6 688.9 11129.9 12597.4 12689.4 12932.7 13323.3 603.4 626.6 659.7 681.4 684.6 4682.0 5358.5 5624.5 5635.7 5903.4 105533.0 115106.4 117383.0 126802.0 134352.3 73325.6 103394.0 109214.0 110589.9 113876.0 95272.0 97902.5 98606.5 101512.0 103169.0 11646.7 12047.4 12390.4 12480.5 12752.8 43380.1 44515.8 44561.6 45069.8 45588.4 57590.7 58333.9 58665.7 59566.1 60610.7 17506.4 17561.3 17580.7 17886.6 17972.6 15039.0 16577.6 16818.0 17212.7 18587.6 15015.3 17585.7 17674.6 18074.8 18266.7 17251.0 17260.6 18273.6 18319.3 18350.5 17526.5 17783.6 17862.2 17975.8 18162.4 11418.9 11535.2 11694.5 12216.5 12237.4 13281.4 14146.9 14211.6 14429.7 14455.1 05:55:48 INFO - PID 2667 | -------- Summary: end -------- 05:55:48 INFO - PID 2667 | 05:55:48 INFO - TEST-INFO | 2667: exit 0 05:55:48 INFO - TEST-OK | dromaeo_css | took 1143327ms 05:55:48 INFO - TEST-START | kraken 05:55:48 INFO - operating with platform_type : mac_ 05:55:48 INFO - Initialising browser for kraken test... 05:55:48 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:55128/getInfo.html -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmphseiM2/profile 05:55:48 INFO - TEST-INFO | started process 2675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:55128/getInfo.html -foreground) 05:56:22 INFO - TEST-INFO | 2675: killed by SIGTERM 05:56:22 INFO - Browser initialized. 05:56:22 INFO - Running cycle 1/1 for kraken test... 05:56:22 INFO - TEST-INFO | started process 2679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmphseiM2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:56:24 INFO - PID 2679 | 2017-06-09 05:56:24.770 plugin-container[2680:298700] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.tsm.portname' 05:56:24 INFO - PID 2679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:24 INFO - PID 2679 | 2017-06-09 05:56:24.770 plugin-container[2680:298700] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0f, name = 'com.apple.CFPasteboardClient' 05:56:24 INFO - PID 2679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:24 INFO - PID 2679 | 2017-06-09 05:56:24.770 plugin-container[2680:298700] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:56:25 INFO - PID 2679 | 2017-06-09 05:56:25.238 plugin-container[2682:298756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 05:56:25 INFO - PID 2679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:25 INFO - PID 2679 | 2017-06-09 05:56:25.238 plugin-container[2682:298756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f03, name = 'com.apple.CFPasteboardClient' 05:56:25 INFO - PID 2679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:25 INFO - PID 2679 | 2017-06-09 05:56:25.238 plugin-container[2682:298756] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:56:34 INFO - PID 2679 | RSS: Main: 254021632 05:56:34 INFO - PID 2679 | 05:56:37 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55128/tests/kraken/driver.html?testName=audio-beat-detection) 05:56:38 INFO - PID 2679 | RSS: Main: 266797056 05:56:38 INFO - PID 2679 | 05:56:41 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55128/tests/kraken/driver.html?testName=audio-dft) 05:56:41 INFO - PID 2679 | RSS: Main: 275914752 05:56:41 INFO - PID 2679 | 05:56:45 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55128/tests/kraken/driver.html?testName=audio-fft) 05:56:46 INFO - PID 2679 | RSS: Main: 277725184 05:56:46 INFO - PID 2679 | 05:56:48 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55128/tests/kraken/driver.html?testName=audio-oscillator) 05:56:48 INFO - PID 2679 | RSS: Main: 280461312 05:56:48 INFO - PID 2679 | 05:56:50 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55128/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:56:51 INFO - PID 2679 | RSS: Main: 281210880 05:56:51 INFO - PID 2679 | 05:56:54 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55128/tests/kraken/driver.html?testName=imaging-darkroom) 05:56:54 INFO - PID 2679 | RSS: Main: 288751616 05:56:54 INFO - PID 2679 | 05:56:57 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55128/tests/kraken/driver.html?testName=imaging-desaturate) 05:56:58 INFO - PID 2679 | RSS: Main: 288894976 05:56:58 INFO - PID 2679 | 05:57:01 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55128/tests/kraken/driver.html?testName=json-parse-financial) 05:57:01 INFO - PID 2679 | RSS: Main: 287690752 05:57:01 INFO - PID 2679 | 05:57:03 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55128/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:57:04 INFO - PID 2679 | RSS: Main: 287629312 05:57:04 INFO - PID 2679 | 05:57:05 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55128/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:57:06 INFO - PID 2679 | RSS: Main: 287711232 05:57:06 INFO - PID 2679 | 05:57:08 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55128/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:57:09 INFO - PID 2679 | RSS: Main: 287825920 05:57:09 INFO - PID 2679 | 05:57:12 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55128/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:57:12 INFO - PID 2679 | RSS: Main: 287633408 05:57:12 INFO - PID 2679 | 05:57:15 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55128/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:57:16 INFO - PID 2679 | RSS: Main: 287633408 05:57:16 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | Cycle 1(1): loaded http://localhost:55128/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55128/tests/kraken/driver.html?testName=ai-astar) 05:57:18 INFO - PID 2679 | RSS: Main: 289579008 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | __start_tp_report 05:57:18 INFO - PID 2679 | _x_x_mozilla_page_load 05:57:18 INFO - PID 2679 | _x_x_mozilla_page_load_details 05:57:18 INFO - PID 2679 | |i|pagename|runs| 05:57:18 INFO - PID 2679 | |0;ai-astar;112;142;141;95;169;138;107;137;160;164 05:57:18 INFO - PID 2679 | |1;audio-beat-detection;138;134;173;153;139;158;112;132;188;139 05:57:18 INFO - PID 2679 | |2;audio-dft;196;202;258;270;216;203;199;234;245;197 05:57:18 INFO - PID 2679 | |3;audio-fft;70;86;75;152;56;61;85;83;161;86 05:57:18 INFO - PID 2679 | |4;audio-oscillator;68;69;109;70;71;74;109;69;70;111 05:57:18 INFO - PID 2679 | |5;imaging-gaussian-blur;89;95;88;92;100;87;93;89;89;89 05:57:18 INFO - PID 2679 | |6;imaging-darkroom;95;101;96;95;99;95;95;96;96;96 05:57:18 INFO - PID 2679 | |7;imaging-desaturate;90;91;89;93;93;89;91;95;91;89 05:57:18 INFO - PID 2679 | |8;json-parse-financial;52;50;52;54;52;51;53;52;53;51 05:57:18 INFO - PID 2679 | |9;json-stringify-tinderbox;56;56;58;61;66;62;57;55;68;83 05:57:18 INFO - PID 2679 | |10;stanford-crypto-aes;84;84;83;84;83;84;88;86;111;115 05:57:18 INFO - PID 2679 | |11;stanford-crypto-ccm;130;135;160;136;133;131;131;135;134;140 05:57:18 INFO - PID 2679 | |12;stanford-crypto-pbkdf2;178;182;176;181;231;221;182;182;177;177 05:57:18 INFO - PID 2679 | |13;stanford-crypto-sha256-iterative;69;64;68;67;65;65;68;62;62;65 05:57:18 INFO - PID 2679 | __end_tp_report 05:57:18 INFO - PID 2679 | __start_cc_report 05:57:18 INFO - PID 2679 | _x_x_mozilla_cycle_collect,1192 05:57:18 INFO - PID 2679 | __end_cc_report 05:57:18 INFO - PID 2679 | __startTimestamp1497013038346__endTimestamp 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | ------- Summary: start ------- 05:57:18 INFO - PID 2679 | Number of tests: 14 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#0] ai-astar Cycles:10 Average:136.50 Median:151.00 stddev:24.97 (16.5%) stddev-sans-first:24.86 05:57:18 INFO - PID 2679 | Values: 112.0 142.0 141.0 95.0 169.0 138.0 107.0 137.0 160.0 164.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#1] audio-beat-detection Cycles:10 Average:146.60 Median:146.00 stddev:21.96 (15.0%) stddev-sans-first:23.07 05:57:18 INFO - PID 2679 | Values: 138.0 134.0 173.0 153.0 139.0 158.0 112.0 132.0 188.0 139.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#2] audio-dft Cycles:10 Average:222.00 Median:225.00 stddev:27.69 (12.3%) stddev-sans-first:27.72 05:57:18 INFO - PID 2679 | Values: 196.0 202.0 258.0 270.0 216.0 203.0 199.0 234.0 245.0 197.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#3] audio-fft Cycles:10 Average:91.50 Median:79.00 stddev:35.89 (45.4%) stddev-sans-first:37.21 05:57:18 INFO - PID 2679 | Values: 70.0 86.0 75.0 152.0 56.0 61.0 85.0 83.0 161.0 86.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:69.50 stddev:19.17 (27.6%) stddev-sans-first:19.65 05:57:18 INFO - PID 2679 | Values: 68.0 69.0 109.0 70.0 71.0 74.0 109.0 69.0 70.0 111.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:89.00 stddev:3.98 (4.5%) stddev-sans-first:4.15 05:57:18 INFO - PID 2679 | Values: 89.0 95.0 88.0 92.0 100.0 87.0 93.0 89.0 89.0 89.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:96.00 stddev:2.01 (2.1%) stddev-sans-first:2.07 05:57:18 INFO - PID 2679 | Values: 95.0 101.0 96.0 95.0 99.0 95.0 95.0 96.0 96.0 96.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:91.00 stddev:2.02 (2.2%) stddev-sans-first:2.11 05:57:18 INFO - PID 2679 | Values: 90.0 91.0 89.0 93.0 93.0 89.0 91.0 95.0 91.0 89.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:1.15 (2.2%) stddev-sans-first:1.22 05:57:18 INFO - PID 2679 | Values: 52.0 50.0 52.0 54.0 52.0 51.0 53.0 52.0 53.0 51.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:61.50 stddev:8.53 (13.9%) stddev-sans-first:8.75 05:57:18 INFO - PID 2679 | Values: 56.0 56.0 58.0 61.0 66.0 62.0 57.0 55.0 68.0 83.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:84.00 stddev:12.15 (14.5%) stddev-sans-first:12.67 05:57:18 INFO - PID 2679 | Values: 84.0 84.0 83.0 84.0 83.0 84.0 88.0 86.0 111.0 115.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#11] stanford-crypto-ccm Cycles:10 Average:136.50 Median:135.00 stddev:8.76 (6.5%) stddev-sans-first:8.97 05:57:18 INFO - PID 2679 | Values: 130.0 135.0 160.0 136.0 133.0 131.0 131.0 135.0 134.0 140.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.70 Median:182.00 stddev:19.93 (11.0%) stddev-sans-first:20.76 05:57:18 INFO - PID 2679 | Values: 178.0 182.0 176.0 181.0 231.0 221.0 182.0 182.0 177.0 177.0 05:57:18 INFO - PID 2679 | 05:57:18 INFO - PID 2679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:2.46 (3.7%) stddev-sans-first:2.26 05:57:18 INFO - PID 2679 | Values: 69.0 64.0 68.0 67.0 65.0 65.0 68.0 62.0 62.0 65.0 05:57:18 INFO - PID 2679 | -------- Summary: end -------- 05:57:18 INFO - PID 2679 | 05:57:19 INFO - TEST-INFO | 2679: exit 0 05:57:19 INFO - TEST-OK | kraken | took 90256ms 05:57:19 INFO - SUITE-END | took 1233s 05:57:19 INFO - Completed test suite (00:20:33) 05:57:19 INFO - javascript benchmark 05:57:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8311.466738915897, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3543.822808859557, 3545.911352216196, 3569.2326149877754, 3605.31499015187, 3617.728670349723, 4770.880727981802, 5176.611754118438, 5329.880725047739, 6027.336992930785, 6184.752609895604, 6062.120992456091, 6129.297663865974, 6133.723982420791, 6142.375998161084, 6216.782412615559, 4495.617872480843, 5260.210968354739, 5414.269073675053, 5414.8917021659545, 5424.457554244564, 5416.241726158341, 5499.890002199953, 5506.256655351536, 5506.366767821712, 5509.063459211923, 5271.446498117719, 5349.170878513836, 5425.565954723613, 5452.1276595744885, 5482.5613950883835, 4471.2845944649025, 5397.946020539823, 5403.594730395197, 5431.864203394907, 5472.753726082336, 4435.268180750172, 5347.25138480613, 5370.22131790892, 5381.811636592701, 5416.350037995427, 5717.171010203498, 5926.940730592682, 6158.0, 6164.321924588292, 6180.81457556274, 6319.778807741753, 6330.936690633081, 6339.239291285029, 6340.144080549159, 6393.680315984182, 6727.66361681914, 6730.798076057726, 6742.157230346207, 6750.189977202767, 6766.458683305373, 6274.717637706314, 6310.810675679737, 6317.5893566918, 6324.3043265240785, 6331.050342448623, 6614.702338394687, 6719.428848547928, 6764.086848275519, 6821.113255276782, 6837.0, 6324.430801227912, 6358.23701155855, 6529.2817790043055, 6540.803775886731, 6587.473002159816, 6512.739490420425, 6661.16735408074, 6666.0, 6685.498587605949, 6702.329767023259, 6611.305812889674, 6613.107230523914, 6619.304972977864, 6623.569467984566, 6705.597664140168, 6365.427111559982, 6415.26224484181, 6510.837229069311, 6551.770688025897, 6639.601623902581, 5315.441878602768, 5317.8404647860625, 5336.226247194135, 5341.305630268041, 5361.115415956324, 4736.8578942631775, 4749.833755818529, 4757.738324392191, 4758.595519380891, 4759.286107083896, 5952.404759524013, 6071.241094863142, 6301.243850737941, 6318.557700960981, 6356.046593011085, 6743.15710536183, 6767.458603311723, 6770.424513916372, 6772.627505487147, 6788.2193547742645, 6335.556511044183, 6350.745970161234, 6355.3009168991375, 6361.459275961595, 6364.268109167412, 5751.626144300607, 5754.223179870748, 5822.592418530747, 5861.208736820475, 5926.940730592725, 5790.478856902899, 6875.656217189121, 6901.551399159039, 6923.030775691508, 6936.895946560705, 6748.392644662004, 6758.86482270342, 6762.594244345355, 6766.458683305422, 6789.626570538668, 6642.070110184674, 6719.563228390139, 6875.278095799869, 6919.446444284546, 6922.619255940972, 6519.445847102859, 6541.443977261986, 6602.438792702674, 6613.272540020497, 6614.4708423325055, 6718.966405167943, 6725.092112564741, 6726.461883049246, 6736.831579210559, 6738.191417029988, 5769.1634712967225, 6235.594686345373, 6298.685065746603, 6455.0, 6517.185351831021, 6457.096006558992, 6490.643014634241, 6494.74021039172, 6494.935050649433, 6511.088447617242, 6280.0, 6362.554621176473, 6371.55399122057, 6395.264544577247, 6396.712147953446, 6316.873662526816, 6532.5753826001155, 6533.444657204001, 6535.901961470677, 6539.803805885831, 6275.435210831047, 6283.214598175228, 6302.590331628429, 6372.012338087604, 6473.935260647333], "value": 6037.752806271009, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.9327067293272, 785.2932360875216, 850.0691742541917, 1247.432418249696, 1300.9544665936683, 32020.519692204634, 39278.21443571127, 39342.803285983566, 39343.40984885229, 39495.60504394955, 39438.802805986, 39747.0, 39755.0, 39782.60217397825, 39861.40207896884, 7710.845783084335, 7803.141654418009, 7807.024121984752, 8278.461899976512, 8494.74515764528, 8380.287675547555, 8474.74575762728, 8475.110113438093, 8480.024797148346, 8488.490690558556, 8435.283000944926, 8499.065102838682, 8505.319574434061, 8512.404131710782, 8520.0, 8434.915650843473, 8483.363747718946, 8486.024107227686, 8486.702965396182, 8487.618057187441, 8339.874901876477, 8380.706675266336, 8398.664053437917, 8408.495490270603, 8429.704960326359, 37075.0, 37190.8140459296, 37201.88394348174, 37219.25561488782, 37363.813180934194, 3498.912527186815, 3521.0845180253064, 3539.610642829287, 3544.5923718772415, 3544.8404821782824, 30711.846440767877, 30764.846175768977, 30765.077047688606, 30821.691783082108, 30844.691553084405, 3029.606151200352, 3037.271054946797, 3051.755859531232, 3084.552739852731, 3089.7837151399413, 239541.0, 243101.0, 251603.0, 291529.0, 292297.0, 47236.05527889491, 47406.0, 47555.0, 47559.76220118877, 47567.04865902662, 50106.74946625317, 50149.0, 50193.99612007739, 50250.246246306335, 50393.74803125961, 14117.294135293194, 14140.292985350898, 14176.0, 14239.50161744334, 14311.92844035794, 11548.884511154782, 11574.768504629861, 11578.71053223676, 11581.363025033535, 11590.072794176445, 11419.714507137222, 13704.52034178799, 13751.587452376445, 13755.724885502434, 13820.654483637989, 5973.014452615356, 6021.157038014681, 6032.849178770478, 6070.362611925684, 6075.5747097703625, 969.9466529340954, 992.965246216379, 1002.6340385759291, 1044.3785947361307, 1048.1876545677162, 23134.305970820904, 28467.857660711565, 29899.0, 30286.485675716125, 31110.0, 1026.8435065093583, 1053.1153830782027, 1075.3924032921375, 1121.2039451989178, 1121.5681962444517, 11936.403179840972, 12000.279983201035, 12365.0, 12408.069394795426, 12487.0, 319785.0, 321086.0, 321851.0, 322077.0, 322579.0, 39673.20653586912, 48310.03379932381, 48582.51417485851, 48633.78415539569, 48662.02675946532, 1342.3892129081244, 1426.572028391499, 1488.5087920986266, 1493.9477118301024, 1500.3848422146868, 22489.212877549206, 22539.0, 23802.52394952126, 23815.404614884766, 23843.523129536967, 37995.620043800314, 38314.42528362133, 38387.0, 38515.807420963836, 38564.42153367758, 280995.0, 281102.0, 282090.0, 282202.0, 287578.1242187551, 28372.43255134927, 28953.85523072371, 31850.362992739552, 33765.32469350648, 33869.0, 39014.0, 39213.01967450837, 40308.798456007535, 40650.593494064684, 41051.0, 27583.586246205923, 28492.145235642965, 28737.0, 28742.856285719274, 29830.55254171146, 28158.155255341553, 28326.0, 28393.716062839932, 29537.0, 29886.850565747904, 34752.957411277705, 34978.825105874304, 35133.47299790556, 35158.64841351554, 35276.29447411089, 28391.00631477971, 28509.429811404072, 28518.57222141628, 29465.0, 29923.401531969674, 22903.312900613008, 23537.764622353556, 23561.0, 23617.88191059034, 23623.88188059049], "value": 17230.164809230908, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.25184826505193, 475.1660835234164, 491.66842034780296, 513.9948600513995, 532.7149974763497, 21315.040823162955, 21598.676019859715, 22485.875706214665, 22518.0, 24245.51508969824, 23591.528169436642, 24119.758802411972, 24241.63637545438, 24284.27147185587, 24391.390215244628, 6414.871702565958, 6525.086487891699, 6541.607503549779, 6542.803715888531, 6544.836379090537, 5986.371430999748, 6405.679716014204, 6447.484201263887, 6454.419102280794, 6521.706523206443, 6212.1303017577575, 6253.937460625404, 6260.7808726694575, 6261.99232491206, 6266.404691554295, 6247.531435142359, 6251.531135164858, 6258.06129080637, 6260.186175797163, 6268.091126786636, 5742.2247996520355, 5742.827715168552, 5750.07998720207, 5750.223719797816, 5759.798407055733, 24700.505989880283, 25242.0, 29156.562651560245, 29749.553756693665, 29774.0, 2187.6499760038478, 2230.6654001899683, 2231.5871563760757, 2236.1726161320253, 2240.4958884251078, 18595.163217655238, 19149.234030638756, 19154.904225478782, 19168.424947251606, 19171.32900348495, 1989.5523507210908, 2016.5765189310116, 2209.8784566848817, 2239.8768067756428, 2254.8872556372116, 191805.04097479425, 197076.0, 200027.0, 200817.99182008285, 203418.0, 12512.186707863959, 15028.919071590297, 15487.070775753491, 15672.294746736425, 15797.0, 20262.0, 20266.392008239778, 20288.289909853083, 20321.49196270105, 20322.288719894732, 9708.708738737849, 9926.156276716416, 9932.553035113437, 9936.850947235796, 9944.950275248577, 9300.627974880928, 9312.627494900127, 9315.72052838416, 9356.672516461891, 9387.34288599805, 8986.86519702205, 9070.909290907006, 9126.908730912739, 9142.040085790895, 9142.405743626643, 2386.8781672613845, 2466.0, 2592.831465954707, 2625.868706564664, 2740.369714965569, 828.6395417993192, 900.8603666431713, 931.2549960031947, 969.8593703912893, 990.4700984973005, 21063.262785802424, 21082.945852707304, 21860.0, 21860.344189674335, 22259.332220033102, 967.4630580028012, 985.9014098590084, 997.7876879591187, 1022.8823685276288, 1026.6201505443034, 10621.468926553796, 10881.455927203766, 11214.607488737855, 11329.943350283196, 11385.886141138648, 549840.2539936533, 570379.0, 586594.0, 594971.0, 596912.0, 150712.0, 152550.0, 152672.70990935146, 152892.0, 156767.4323256753, 992.335135459244, 1044.253358848427, 1133.3823066428815, 1163.1857699610307, 1200.1538915065032, 22340.21809236669, 23298.53402931898, 24157.15449959243, 24377.63433548463, 24453.0, 68643.0, 70753.29246707672, 70805.29194708193, 72597.54804903977, 73731.0, 97645.0, 98155.01844981458, 98255.0, 98593.50703246437, 98810.0, 11296.661100166679, 11804.409779511161, 12039.0, 12044.819327710267, 12114.21257618252, 18661.253549858397, 18698.158582863533, 18844.717329240346, 18957.862528248348, 18986.240550377832, 12377.319247441477, 12388.070894683115, 12540.811887821508, 12555.12114152037, 12687.0, 13126.803097953347, 13190.868091319348, 13219.47122115143, 13226.338683066, 13241.3379331035, 14455.49405770835, 15277.00699454532, 15612.609684757972, 15682.921585392456, 15813.288402021706, 11690.4739286734, 11742.354170520703, 11863.228890122115, 11873.346965916957, 11895.821562676394, 14983.7003259932, 15093.773593395888, 15095.924520377328, 15101.32044057998, 15110.39558417695], "value": 11715.213016942993, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1373.0, 1433.6702558411569, 1436.3105709259548, 1446.3925151436401, 1453.6147920800984, 13077.149985250951, 13196.53812116576, 13703.177809331448, 13738.587842364721, 13983.440662373492, 13513.729725405487, 13745.587632371045, 13755.105918115316, 13761.862381376182, 13794.862051379483, 2679.115891755726, 2723.332783468053, 2730.2218867622732, 2731.754142127208, 2742.3966727320058, 2248.651459023854, 2342.0982921575232, 2633.367991681992, 2655.7609815116634, 2682.7317268273214, 2693.6902256240587, 2741.191348552172, 2742.9862850685818, 2743.27303264635, 2749.8900043998215, 2325.802306803924, 2670.212287375219, 2697.150397624752, 2701.486717523684, 2710.0785732850927, 2675.304420850593, 2679.397135644484, 2689.515887140314, 2691.380982374065, 2695.380062585616, 13223.074384793068, 13296.933515332457, 13334.666633334147, 13348.86651133486, 13372.264525451097, 7213.242609526029, 7619.4285428593075, 7628.923710762877, 7638.5798781067015, 7656.196099409593, 4544.590986811169, 4688.249880019214, 4721.976390118062, 4777.5222477752295, 4827.782749776268, 1372.5196181336453, 1378.827646544182, 1382.7234553089422, 1409.4784929576122, 1410.2173293821925, 19582.90208548948, 19680.70478942817, 19699.11353989074, 19763.802361976483, 19768.604627907505, 6491.935080649133, 6492.156019717407, 6507.219133703986, 6553.2136143663065, 6610.86778264432, 14130.646733831736, 14171.433142674383, 14182.716345673236, 14188.432462701376, 14211.0, 6390.233172019387, 7221.530600511055, 7342.0, 7343.926560734324, 7355.117385913724, 6576.671166441659, 6718.966405167943, 6758.628275444799, 6762.594244345257, 6763.93236067633, 4924.4090709115135, 5039.571636410946, 5045.369328833895, 5047.87380315495, 5065.949340506621, 1303.6154334471353, 1348.447136673959, 1412.329143656755, 1441.4234306277363, 1442.819647544057, 818.1818181818181, 833.9874901876356, 856.1310270075878, 856.208007592974, 893.883795106632, 7541.962290188624, 7702.537847729154, 7835.725749598737, 7954.443188976832, 8184.7953801155445, 940.3135710931059, 941.091846368258, 954.494118117385, 963.8698775665335, 981.6122631560494, 5261.552768014762, 5330.680159190461, 5415.214793854869, 5475.151351540518, 5574.498295153456, 17299.827001730075, 17796.021218833088, 18124.45626631203, 18128.637427251644, 18217.635647287247, 17861.464156075337, 17910.820891790914, 17957.0, 18038.27846886136, 18046.368377106737, 9022.86465703015, 9302.25581953442, 9330.720078397659, 9529.237660987104, 9549.188318992825, 7809.0, 7933.8413231736195, 8245.216704413071, 8472.915270847212, 8477.152284771473, 15765.369385224463, 15987.520374388787, 16197.0, 16398.098104603883, 16591.751123732913, 16410.83589164093, 16463.91768041152, 16595.91702041482, 16771.748423773897, 17023.0, 10393.37639741618, 10853.240273180802, 10957.397343146204, 10974.615888443866, 11016.11871050298, 12419.75160496803, 12524.06069544806, 12525.185862918881, 12540.435680394221, 12545.435455404346, 9786.951065244628, 9806.460644664612, 9832.065953734384, 9974.850377244202, 10168.034036766496, 9749.756256093655, 9810.607575696893, 9882.4564648945, 9975.351602145838, 9997.050280223368, 9100.271978241592, 9313.813723725623, 9327.253819694544, 9345.439273643604, 9347.252219822534, 6530.869382612416, 6613.371729685672, 7026.402755765817, 7038.40173585251, 7097.219305876246, 6917.132374258821, 6939.826504337432, 6948.583085014915, 7067.399271062018, 7239.239879812754], "value": 6104.517722132237, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.8162312640254, 463.756527822893, 467.91577516047096, 468.34431795486313, 479.697790392053, 5655.123455864347, 5747.683877386742, 6518.250401203863, 6567.868642627144, 6590.242122155953, 6176.629402235858, 6308.243010838694, 6412.166418365618, 6420.807375778734, 6430.5242481644555, 4341.153475072362, 4557.794899229542, 4801.447833499157, 4819.397575303087, 4831.178699621055, 4272.803614987808, 4606.216943119678, 4789.808407663707, 4800.735959522242, 4819.325294458778, 3679.0, 4201.369794530814, 4370.672199585044, 4760.809567617292, 4796.90406191876, 4802.375691160162, 4815.374001379833, 4832.637552183583, 4833.540813622701, 4851.830185943492, 4204.894877628053, 4210.810513526899, 4214.3889136075095, 4218.860907554974, 4244.384564238168, 5541.750621222014, 6338.366163383671, 6367.458766004896, 6384.7126879290545, 6393.680315984182, 5724.971375143139, 5731.369549349568, 5746.6325592549965, 5763.221965034709, 5784.929787989236, 6963.40811031063, 6970.581765094111, 6987.475939304572, 6988.056612357317, 6990.825229369256, 4807.759612019385, 4812.25410061441, 4815.277708343741, 4824.5044036348845, 4847.709137451764, 6224.626522408625, 6253.78111766086, 6966.860662786767, 7220.494565380425, 7306.2693730626515, 4760.09558183944, 4842.661013729005, 4924.3105965164905, 4937.259411088294, 4960.082384758831, 3919.6668283195677, 4508.909821803545, 4617.3304870793545, 5047.924281135786, 5051.87370315745, 5500.092484739973, 5546.473085056913, 5641.37675980671, 5668.8866222675315, 5734.16854556087, 5531.612787104864, 6887.965560172167, 6971.829930511432, 6973.930260697328, 6977.302269773084, 1646.5965838369436, 1702.880798344104, 1715.6311393050555, 1718.3384397007242, 1723.6983527882571, 1110.7833972375308, 1137.8791889988354, 1157.8726340102583, 1157.9826302605468, 1159.2291126400848, 5678.716064196774, 5968.76124954997, 6684.030815531721, 6728.293529179464, 6734.865302693918, 6427.903581446189, 7202.351788339075, 7227.096612923385, 7234.819129521804, 7312.1956584775635, 6361.61830290184, 6387.457066149336, 6468.482521398277, 6579.967100164565, 6608.702608382634, 3473.4095203815414, 3518.7360947928496, 3735.364987952055, 3761.887143385703, 3788.6400791924725, 6846.691898864563, 7426.509540515949, 7427.442941779388, 7429.44279179053, 7469.887951680729, 6839.555428897106, 7367.447441441914, 7378.0, 7431.51695139825, 7437.367823734935, 5981.910271346021, 7409.259074092655, 7442.96278518604, 7475.850482990419, 7513.867372944967, 6158.27568280882, 6608.240052394036, 6620.775156596045, 7175.210726819942, 7225.710971561289, 6214.844628884133, 6569.868602627825, 7059.647017648994, 7073.929260707327, 7135.678894449658, 5580.9162862557905, 7155.141383034069, 7173.852183650591, 7456.0, 7482.2517748224745, 4880.145974454385, 4903.975480122576, 4915.26271059344, 4922.212446008622, 4928.654994150375, 5810.0, 6313.463355614824, 6965.79102626922, 7092.9645351772915, 7123.679434425359, 6877.690503927235, 6920.342567456285, 6923.653817309215, 6936.618485983319, 7006.75476358325, 6843.213030501556, 6880.724771009103, 6892.724291028303, 6901.378875901193, 7020.333068358498, 6771.307173206698, 6787.457003439614, 6826.624535650587, 6829.214640316229, 6832.829179270558, 5572.526335261386, 6862.245153033262, 6895.207051188977, 6902.0, 6904.827379315558, 6392.4246817786625, 6727.798166055026, 6735.4274886635185, 6737.326267373223, 6761.898571521333], "value": 5099.078745323744, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.2480818414322, 825.4057078903188, 842.3050982939073, 868.0971789339088, 875.7985663297442, 13851.653708657288, 15056.6988660227, 15403.92172547922, 16398.83601163988, 16946.576335591617, 13938.303084845767, 17038.914805425986, 17724.29102835885, 17763.28946842128, 17792.733109003377, 980.2207245240033, 1000.1536410449598, 1003.8845532763717, 1006.2050979726008, 1010.2019404670301, 1013.6502906497284, 1035.8135535603587, 1038.1435315864442, 1040.230229630072, 1047.638564695179, 957.3059531839431, 1076.43487169236, 1077.062955228952, 1079.4818487126183, 1080.8702955645333, 1059.7562560611025, 1066.53605681528, 1067.0716476665311, 1067.2795862792584, 1068.3643232276784, 770.1528318849277, 781.9061712594469, 785.4855069929206, 790.2295262119387, 792.6551949901753, 15049.849501505063, 16762.497125086265, 17311.653766924715, 17325.393611223546, 17569.121543922753, 17101.657966840718, 17185.656286874193, 17575.384861529914, 17696.469105926844, 17711.202995865217, 14426.495072672511, 14749.483768068174, 14765.557033289018, 14769.852301477062, 14800.629984250372, 3574.857005719794, 3588.820558972067, 3600.369935261345, 3600.4059330210484, 3601.1897323102353, 138549.0, 138746.0, 139773.60226397708, 140903.0, 141745.291273544, 12518.561850335194, 13411.798823017663, 13912.791308130387, 14038.157710537196, 14157.575272741835, 26035.869820650776, 26544.805483753276, 26855.597166042524, 26871.328216794736, 27137.864310678717, 7238.276172382825, 7279.2720727927845, 7354.558726476428, 7404.777856664308, 7423.146338171179, 3080.522519009557, 3505.2463720299747, 3783.1677031053673, 3958.802059897051, 3960.9801950990636, 4531.43357080365, 4623.16782979067, 4639.652026098056, 4648.651351148677, 4649.000464900063, 988.7330420786348, 1009.8232809258351, 1022.2077889635443, 1030.5826140413144, 1033.2973577966904, 634.5494698763837, 641.2909595926895, 657.4641667041346, 687.5805758487319, 688.8691148681835, 11129.944350278358, 12597.370131493388, 12689.36553172356, 12932.741345173044, 13323.267220302783, 603.3571666029945, 626.5770604841714, 659.6668682315496, 681.4241965539112, 684.6405637040593, 4682.0, 5358.517733404013, 5624.465675760796, 5635.69003704792, 5903.3801450847905, 105533.0, 115106.4244678788, 117383.0, 126802.0, 134352.32823835817, 73325.6333718328, 103393.96606033994, 109214.0, 110589.89410105957, 113876.0, 95272.0, 97902.5104874471, 98606.50696746471, 101512.0, 103168.96831031743, 11646.650600481998, 12047.397630118634, 12390.380480976095, 12480.5007799687, 12752.808707869575, 43380.13239735251, 44515.77742111269, 44561.55438445574, 45069.774651126536, 45588.404405845635, 57590.7120464395, 58333.91662583579, 58665.70667146637, 59566.106508403274, 60610.69694651499, 17506.387276445264, 17561.297548097933, 17580.73628895593, 17886.5528361792, 17972.55068623295, 15039.0, 16577.585560361087, 16817.990920544806, 17212.741808872626, 18587.628247435245, 15015.324310406277, 17585.736213957058, 17674.5581360467, 18074.819251807312, 18266.72599911029, 17251.0, 17260.568485787957, 18273.634527309645, 18319.267229310677, 18350.541236469195, 17526.474205773848, 17783.644327113125, 17862.19620117072, 17975.820241797937, 18162.364317248834, 11418.885811141783, 11535.192536522363, 11694.47374868116, 12216.511339546674, 12237.449314781034, 13281.40233689467, 14146.858531414555, 14211.64470888236, 14429.711405771615, 14455.060421072596], "value": 8689.224533730223, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 142.0, 141.0, 95.0, 169.0, 138.0, 107.0, 137.0, 160.0, 164.0], "value": 136.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 134.0, 173.0, 153.0, 139.0, 158.0, 112.0, 132.0, 188.0, 139.0], "value": 146.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 202.0, 258.0, 270.0, 216.0, 203.0, 199.0, 234.0, 245.0, 197.0], "value": 222.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 86.0, 75.0, 152.0, 56.0, 61.0, 85.0, 83.0, 161.0, 86.0], "value": 91.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 109.0, 70.0, 71.0, 74.0, 109.0, 69.0, 70.0, 111.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 88.0, 92.0, 100.0, 87.0, 93.0, 89.0, 89.0, 89.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 101.0, 96.0, 95.0, 99.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 89.0, 93.0, 93.0, 89.0, 91.0, 95.0, 91.0, 89.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 52.0, 54.0, 52.0, 51.0, 53.0, 52.0, 53.0, 51.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 58.0, 61.0, 66.0, 62.0, 57.0, 55.0, 68.0, 83.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 83.0, 84.0, 88.0, 86.0, 111.0, 115.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 135.0, 160.0, 136.0, 133.0, 131.0, 131.0, 135.0, 134.0, 140.0], "value": 136.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 182.0, 176.0, 181.0, 231.0, 221.0, 182.0, 182.0, 177.0, 177.0], "value": 188.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 68.0, 67.0, 65.0, 65.0, 68.0, 62.0, 62.0, 65.0], "value": 65.5, "unit": "score"}]}]} 05:57:19 INFO - Return code: 0 05:57:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:57:19 INFO - # TBPL SUCCESS # 05:57:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:57:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:57:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:57:19 INFO - 2017-06-09 05:57:19.759 screenresolution[2688:299153] starting screenresolution argv=screenresolution get 05:57:19 INFO - 2017-06-09 05:57:19.761 screenresolution[2688:299153] Display 0: 1600x1200x32@60 05:57:19 INFO - 2017-06-09 05:57:19.767 screenresolution[2689:299155] starting screenresolution argv=screenresolution list 05:57:19 INFO - Available Modes on Display 0 05:57:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:57:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:57:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:57:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:57:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:57:19 INFO - Intel Iris: 05:57:19 INFO - Chipset Model: Intel Iris 05:57:19 INFO - Type: GPU 05:57:19 INFO - Bus: Built-In 05:57:19 INFO - VRAM (Dynamic, Max): 1536 MB 05:57:19 INFO - Vendor: Intel (0x8086) 05:57:19 INFO - Device ID: 0x0a2e 05:57:19 INFO - Revision ID: 0x0009 05:57:19 INFO - Displays: 05:57:19 INFO - Display: 05:57:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:57:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:57:19 INFO - Display Serial Number: 1600x1200 60 05:57:19 INFO - Main Display: Yes 05:57:19 INFO - Mirror: Off 05:57:19 INFO - Online: Yes 05:57:19 INFO - Rotation: Supported 05:57:20 INFO - Return code: 0 05:57:20 INFO - Running post-action listener: _resource_record_post_action 05:57:20 INFO - [mozharness: 2017-06-09 12:57:20.000711Z] Finished run-tests step (success) 05:57:20 INFO - Running post-run listener: _resource_record_post_run 05:57:20 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 5840384; Write bytes: 577462272; Read time: 148; Write time: 1763 05:57:20 INFO - TinderboxPrint: CPU usage
53.3% 05:57:20 INFO - TinderboxPrint: I/O read bytes / time
5,840,384 / 148 05:57:20 INFO - TinderboxPrint: I/O write bytes / time
577,462,272 / 1,763 05:57:20 INFO - TinderboxPrint: CPU idle
2,348.2 (46.7%) 05:57:20 INFO - TinderboxPrint: CPU system
1,013.4 (20.2%) 05:57:20 INFO - TinderboxPrint: CPU user
1,664.2 (33.1%) 05:57:20 INFO - TinderboxPrint: Swap in / out
271,233,024 / 0 05:57:20 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 234048000; Write bytes: 226570240; Read time: 16841; Write time: 457 05:57:20 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:57:20 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 5840384; Write bytes: 324677632; Read time: 148; Write time: 1232 05:57:20 INFO - Running post-run listener: _upload_blobber_files 05:57:20 INFO - Blob upload gear active. 05:57:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:57:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:57:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:57:20 INFO - (blobuploader) - INFO - Open directory for files ... 05:57:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:57:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:57:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:21 INFO - (blobuploader) - INFO - Done attempting. 05:57:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:57:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:57:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:22 INFO - (blobuploader) - INFO - Done attempting. 05:57:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:57:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:57:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:24 INFO - (blobuploader) - INFO - Done attempting. 05:57:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:57:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:57:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:25 INFO - (blobuploader) - INFO - Done attempting. 05:57:25 INFO - (blobuploader) - INFO - Iteration through files over. 05:57:25 INFO - Return code: 0 05:57:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:57:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:57:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d71a6fcca86079310293bf12f72c47af2e21339ea75bbd660b60a878628088927e3d9338abf11431ef93b86f824d3222b52050414069f97c473feede5f0c5b67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0ac56bf2dcdbb580782f5ced31917fffea4460096f3180e4abc6d856744738a8b5faccd1229908bf2a7b740757b5fcec0a0d871f582c20ef05bde75eec1645", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d83ee70f34d81ebd9b7c9c855526a62aeaa8a04a05fdc14157bd2b2e0a4da98d60bc405a311db2fce9f257ebfb4b70aa15b78c3ac7421be2cf5fa980e97a6c3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/279ee3378fcbc6282281576ef9e66bdb3a6a9d5b2cada619e4b10b2d4d5414e28e261dedcbca6745207eee58edcd49fa01d2437c2e3b9a89d8f876200f803415"} 05:57:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:57:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:57:25 INFO - Contents: 05:57:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d71a6fcca86079310293bf12f72c47af2e21339ea75bbd660b60a878628088927e3d9338abf11431ef93b86f824d3222b52050414069f97c473feede5f0c5b67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0ac56bf2dcdbb580782f5ced31917fffea4460096f3180e4abc6d856744738a8b5faccd1229908bf2a7b740757b5fcec0a0d871f582c20ef05bde75eec1645", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d83ee70f34d81ebd9b7c9c855526a62aeaa8a04a05fdc14157bd2b2e0a4da98d60bc405a311db2fce9f257ebfb4b70aa15b78c3ac7421be2cf5fa980e97a6c3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/279ee3378fcbc6282281576ef9e66bdb3a6a9d5b2cada619e4b10b2d4d5414e28e261dedcbca6745207eee58edcd49fa01d2437c2e3b9a89d8f876200f803415"} 05:57:25 INFO - Running post-run listener: copy_logs_to_upload_dir 05:57:25 INFO - Copying logs to upload dir... 05:57:25 INFO - mkdir: /builds/slave/test/build/upload/logs 05:57:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.611698 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-09 05:57:26.044785) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-09 05:57:26.048101) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CJj1ErsY2o/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d71a6fcca86079310293bf12f72c47af2e21339ea75bbd660b60a878628088927e3d9338abf11431ef93b86f824d3222b52050414069f97c473feede5f0c5b67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0ac56bf2dcdbb580782f5ced31917fffea4460096f3180e4abc6d856744738a8b5faccd1229908bf2a7b740757b5fcec0a0d871f582c20ef05bde75eec1645", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d83ee70f34d81ebd9b7c9c855526a62aeaa8a04a05fdc14157bd2b2e0a4da98d60bc405a311db2fce9f257ebfb4b70aa15b78c3ac7421be2cf5fa980e97a6c3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/279ee3378fcbc6282281576ef9e66bdb3a6a9d5b2cada619e4b10b2d4d5414e28e261dedcbca6745207eee58edcd49fa01d2437c2e3b9a89d8f876200f803415"} build_url:https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010952 build_url: 'https://queue.taskcluster.net/v1/task/LtnFcVSkRyWnwHEf4VnYfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d71a6fcca86079310293bf12f72c47af2e21339ea75bbd660b60a878628088927e3d9338abf11431ef93b86f824d3222b52050414069f97c473feede5f0c5b67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0ac56bf2dcdbb580782f5ced31917fffea4460096f3180e4abc6d856744738a8b5faccd1229908bf2a7b740757b5fcec0a0d871f582c20ef05bde75eec1645", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d83ee70f34d81ebd9b7c9c855526a62aeaa8a04a05fdc14157bd2b2e0a4da98d60bc405a311db2fce9f257ebfb4b70aa15b78c3ac7421be2cf5fa980e97a6c3c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/279ee3378fcbc6282281576ef9e66bdb3a6a9d5b2cada619e4b10b2d4d5414e28e261dedcbca6745207eee58edcd49fa01d2437c2e3b9a89d8f876200f803415"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-09 05:57:26.081097) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:57:26.081419) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CJj1ErsY2o/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.yxxVC95YAf/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005288 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 05:57:26.130318) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-09 05:57:26.130612) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-09 05:57:26.131097) ========= ========= Total master_lag: 0.18 =========