builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0230 starttime: 1472925877.61 results: success (0) buildid: 20160903081850 builduid: 2868bb92124e4b189d41aab81e0dfb5c revision: c6906f5fc27809e2775e88a2ccc88fb794c93f4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:37.612474) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:37.612906) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:37.613224) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:37.820686) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:37.820941) ========= 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.7pKeEVq8iw/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.0U6NUrrchn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 --2016-09-03 11:04:37-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.3M=0.001s 2016-09-03 11:04:38 (10.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.587566 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:38.431349) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:38.434706) ========= 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.7pKeEVq8iw/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.0U6NUrrchn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.020501 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:38.483341) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:38.486749) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c6906f5fc27809e2775e88a2ccc88fb794c93f4d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c6906f5fc27809e2775e88a2ccc88fb794c93f4d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7pKeEVq8iw/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.0U6NUrrchn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 2016-09-03 11:04:38,579 truncating revision to first 12 chars 2016-09-03 11:04:38,579 Setting DEBUG logging. 2016-09-03 11:04:38,580 attempt 1/10 2016-09-03 11:04:38,580 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c6906f5fc278?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 11:04:39,233 unpacking tar archive at: mozilla-central-c6906f5fc278/testing/mozharness/ program finished with exit code 0 elapsedTime=0.931893 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:39.443390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:39.443788) ========= script_repo_revision: c6906f5fc27809e2775e88a2ccc88fb794c93f4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:39.444446) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:39.444948) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:39.464553) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-03 11:04:39.464895) ========= /tools/buildbot/bin/python 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', '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:04:39 INFO - MultiFileLogger online at 20160903 11:04:39 in /builds/slave/test 11:04:39 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 11:04:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:04:39 INFO - {'append_to_log': False, 11:04:39 INFO - 'base_work_dir': '/builds/slave/test', 11:04:39 INFO - 'blob_upload_branch': 'Firefox', 11:04:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:04:39 INFO - 'branch': 'Firefox', 11:04:39 INFO - 'buildbot_json_path': 'buildprops.json', 11:04:39 INFO - 'config_files': ('talos/mac_config.py',), 11:04:39 INFO - 'default_actions': ('clobber', 11:04:39 INFO - 'read-buildbot-config', 11:04:39 INFO - 'download-and-extract', 11:04:39 INFO - 'populate-webroot', 11:04:39 INFO - 'create-virtualenv', 11:04:39 INFO - 'install', 11:04:39 INFO - 'run-tests'), 11:04:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:04:39 INFO - 'download_minidump_stackwalk': True, 11:04:39 INFO - 'download_symbols': 'ondemand', 11:04:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:04:39 INFO - 'tooltool.py': '/tools/tooltool.py', 11:04:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:04:39 INFO - '/tools/misc-python/virtualenv.py')}, 11:04:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:04:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:04:39 INFO - 'installer_path': 'installer.exe', 11:04:39 INFO - 'log_level': 'info', 11:04:39 INFO - 'log_name': 'talos', 11:04:39 INFO - 'log_to_console': True, 11:04:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:04:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:04:39 INFO - 'opt_config_files': (), 11:04:39 INFO - 'pip_index': False, 11:04:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:04:39 INFO - 'cmd': ('bash', 11:04:39 INFO - '-c', 11:04:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:04:39 INFO - 'enabled': True, 11:04:39 INFO - 'halt_on_failure': False, 11:04:39 INFO - 'name': 'check_screen_resolution'},), 11:04:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:04:39 INFO - 'cmd': ('bash', 11:04:39 INFO - '-c', 11:04:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:04:39 INFO - 'enabled': True, 11:04:39 INFO - 'halt_on_failure': False, 11:04:39 INFO - 'name': 'check_screen_resolution'},), 11:04:39 INFO - 'run_cmd_checks_enabled': True, 11:04:39 INFO - 'sps_profile': False, 11:04:39 INFO - 'sps_profile_interval': 0, 11:04:39 INFO - 'suite': 'dromaeojs-e10s', 11:04:39 INFO - 'system_bits': '32', 11:04:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:04:39 INFO - 'title': 't-yosemite-r7-0230', 11:04:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:04:39 INFO - 'use_talos_json': True, 11:04:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:04:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:04:39 INFO - 'work_dir': 'build'} 11:04:39 INFO - [mozharness: 2016-09-03 18:04:39.677485Z] Running clobber step. 11:04:39 INFO - Running pre-action listener: _resource_record_pre_action 11:04:39 INFO - Running main action method: clobber 11:04:39 INFO - rmtree: /builds/slave/test/build 11:04:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:04:40 INFO - Running post-action listener: _resource_record_post_action 11:04:40 INFO - [mozharness: 2016-09-03 18:04:40.630087Z] Finished clobber step (success) 11:04:40 INFO - [mozharness: 2016-09-03 18:04:40.630199Z] Running read-buildbot-config step. 11:04:40 INFO - Running pre-action listener: _resource_record_pre_action 11:04:40 INFO - Running main action method: read_buildbot_config 11:04:40 INFO - Using buildbot properties: 11:04:40 INFO - { 11:04:40 INFO - "project": "", 11:04:40 INFO - "product": "firefox", 11:04:40 INFO - "script_repo_revision": "production", 11:04:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:04:40 INFO - "repository": "", 11:04:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:04:40 INFO - "buildid": "20160903081850", 11:04:40 INFO - "pgo_build": "False", 11:04:40 INFO - "basedir": "/builds/slave/test", 11:04:40 INFO - "buildnumber": 107, 11:04:40 INFO - "slavename": "t-yosemite-r7-0230", 11:04:40 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:04:40 INFO - "platform": "yosemite_r7", 11:04:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:04:40 INFO - "branch": "mozilla-central", 11:04:40 INFO - "repo_path": "mozilla-central", 11:04:40 INFO - "revision": "c6906f5fc27809e2775e88a2ccc88fb794c93f4d", 11:04:40 INFO - "stage_platform": "macosx64", 11:04:40 INFO - "builduid": "2868bb92124e4b189d41aab81e0dfb5c", 11:04:40 INFO - "slavebuilddir": "test" 11:04:40 INFO - } 11:04:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:04:40 INFO - Running post-action listener: _resource_record_post_action 11:04:40 INFO - [mozharness: 2016-09-03 18:04:40.633129Z] Finished read-buildbot-config step (success) 11:04:40 INFO - [mozharness: 2016-09-03 18:04:40.633229Z] Running download-and-extract step. 11:04:40 INFO - Running pre-action listener: _resource_record_pre_action 11:04:40 INFO - Running main action method: download_and_extract 11:04:40 INFO - mkdir: /builds/slave/test/build/tests 11:04:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:04:40 INFO - trying https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:04:40 INFO - Downloading https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:04:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 11:04:42 INFO - Downloaded 1199 bytes. 11:04:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:04:42 INFO - Using the following test package requirements: 11:04:42 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:04:42 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:04:42 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:04:42 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:04:42 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:04:42 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:04:42 INFO - u'jsshell-mac.zip'], 11:04:42 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:04:42 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:04:42 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:04:42 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:04:42 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:04:42 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:04:42 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:04:42 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:04:42 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:04:42 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:04:42 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:04:42 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:04:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:04:42 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:04:50 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:04:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:04:50 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:04:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:04:58 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:04:59 INFO - trying https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:04:59 INFO - Downloading https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:04:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 11:05:02 INFO - Downloaded 111484377 bytes. 11:05:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:05:02 INFO - mkdir: /builds/slave/test/properties 11:05:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:05:02 INFO - Writing to file /builds/slave/test/properties/build_url 11:05:02 INFO - Contents: 11:05:02 INFO - build_url:https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:05:02 INFO - Running post-action listener: _resource_record_post_action 11:05:02 INFO - Running post-action listener: set_extra_try_arguments 11:05:02 INFO - [mozharness: 2016-09-03 18:05:02.951935Z] Finished download-and-extract step (success) 11:05:02 INFO - [mozharness: 2016-09-03 18:05:02.952084Z] Running populate-webroot step. 11:05:02 INFO - Running pre-action listener: _resource_record_pre_action 11:05:02 INFO - Running main action method: populate_webroot 11:05:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:05:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:05:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:05:02 INFO - '--tppagecycles', 11:05:02 INFO - '7'], 11:05:02 INFO - 'tests': ['tsvgm']}}, 11:05:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:05:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:05:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:05:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:05:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:02 INFO - 'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:05:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:05:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:02 INFO - 'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['damp', 'tps']}, 11:05:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:02 INFO - 'tests': ['damp', 'tps']}, 11:05:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['dromaeo_dom']}, 11:05:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:05:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['basic_compositor_video']}, 11:05:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:05:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['a11yr', 11:05:02 INFO - 'ts_paint', 11:05:02 INFO - 'tpaint', 11:05:02 INFO - 'sessionrestore', 11:05:02 INFO - 'sessionrestore_no_auto_restore', 11:05:02 INFO - 'tabpaint']}, 11:05:02 INFO - 'other-e10s': {'tests': ['a11yr', 11:05:02 INFO - 'ts_paint', 11:05:02 INFO - 'tpaint', 11:05:02 INFO - 'sessionrestore', 11:05:02 INFO - 'sessionrestore_no_auto_restore', 11:05:02 INFO - 'tabpaint']}, 11:05:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:05:02 INFO - 'ts_paint', 11:05:02 INFO - 'tpaint', 11:05:02 INFO - 'sessionrestore', 11:05:02 INFO - 'sessionrestore_no_auto_restore', 11:05:02 INFO - 'tabpaint']}, 11:05:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:05:02 INFO - 'ts_paint', 11:05:02 INFO - 'tpaint', 11:05:02 INFO - 'sessionrestore', 11:05:02 INFO - 'sessionrestore_no_auto_restore', 11:05:02 INFO - 'tabpaint']}, 11:05:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['a11yr', 11:05:02 INFO - 'ts_paint', 11:05:02 INFO - 'tpaint', 11:05:02 INFO - 'sessionrestore', 11:05:02 INFO - 'sessionrestore_no_auto_restore', 11:05:02 INFO - 'tabpaint']}, 11:05:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['a11yr', 11:05:02 INFO - 'ts_paint', 11:05:02 INFO - 'tpaint', 11:05:02 INFO - 'sessionrestore', 11:05:02 INFO - 'sessionrestore_no_auto_restore', 11:05:02 INFO - 'tabpaint']}, 11:05:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['tsvgx', 11:05:02 INFO - 'tsvgr_opacity', 11:05:02 INFO - 'tart', 11:05:02 INFO - 'tscrollx', 11:05:02 INFO - 'cart']}, 11:05:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:05:02 INFO - 'tsvgr_opacity', 11:05:02 INFO - 'tart', 11:05:02 INFO - 'tscrollx', 11:05:02 INFO - 'cart']}, 11:05:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:02 INFO - 'talos_options': ['--disable-e10s'], 11:05:02 INFO - 'tests': ['tp5o']}, 11:05:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:02 INFO - 'tests': ['tp5o']}, 11:05:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:02 INFO - 'talos_options': ['--disable-e10s', 11:05:02 INFO - '--xperf_path', 11:05:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:05:02 INFO - 'tests': ['tp5n']}, 11:05:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:02 INFO - 'talos_options': ['--xperf_path', 11:05:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:05:02 INFO - 'tests': ['tp5n']}}, 11:05:02 INFO - 'talos.zip': {'path': '', 11:05:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:05:02 INFO - Running post-action listener: _resource_record_post_action 11:05:02 INFO - [mozharness: 2016-09-03 18:05:02.970757Z] Finished populate-webroot step (success) 11:05:02 INFO - [mozharness: 2016-09-03 18:05:02.970871Z] Running create-virtualenv step. 11:05:02 INFO - Running pre-action listener: _resource_record_pre_action 11:05:02 INFO - Running main action method: create_virtualenv 11:05:02 INFO - Creating virtualenv /builds/slave/test/build/venv 11:05:02 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 11:05:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:05:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:05:03 INFO - Using real prefix '/tools/python27' 11:05:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:05:03 INFO - Installing distribute.............................................................................................................................................................................................done. 11:05:06 INFO - Installing pip.................done. 11:05:06 INFO - Return code: 0 11:05:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:06 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 11:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:05:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:06 INFO - 'HOME': '/Users/cltbld', 11:05:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:06 INFO - 'LOGNAME': 'cltbld', 11:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:06 INFO - 'MOZ_NO_REMOTE': '1', 11:05:06 INFO - 'NO_EM_RESTART': '1', 11:05:06 INFO - 'PAGER': '/bin/cat', 11:05:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:06 INFO - 'PWD': '/builds/slave/test', 11:05:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:06 INFO - 'SHELL': '/bin/bash', 11:05:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:06 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:06 INFO - 'USER': 'cltbld', 11:05:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:06 INFO - 'XPC_FLAGS': '0x0', 11:05:06 INFO - 'XPC_SERVICE_NAME': '0', 11:05:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:06 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 11:05:06 INFO - Cleaning up... 11:05:06 INFO - Return code: 0 11:05:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:06 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 11:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:05:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:06 INFO - 'HOME': '/Users/cltbld', 11:05:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:06 INFO - 'LOGNAME': 'cltbld', 11:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:06 INFO - 'MOZ_NO_REMOTE': '1', 11:05:06 INFO - 'NO_EM_RESTART': '1', 11:05:06 INFO - 'PAGER': '/bin/cat', 11:05:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:06 INFO - 'PWD': '/builds/slave/test', 11:05:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:06 INFO - 'SHELL': '/bin/bash', 11:05:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:06 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:06 INFO - 'USER': 'cltbld', 11:05:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:06 INFO - 'XPC_FLAGS': '0x0', 11:05:06 INFO - 'XPC_SERVICE_NAME': '0', 11:05:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:07 INFO - Downloading/unpacking psutil>=3.1.1 11:05: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 11:05: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 11:05:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05: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 11:05: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 11:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:05:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:10 INFO - Installing collected packages: psutil 11:05:10 INFO - Running setup.py install for psutil 11:05:10 INFO - building 'psutil._psutil_osx' extension 11:05:11 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 11:05:11 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 11:05:11 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 11:05:11 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 11:05:11 INFO - building 'psutil._psutil_posix' extension 11:05:11 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 11:05:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:05:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:05:11 INFO - ^ 11:05:11 INFO - 1 warning generated. 11:05:11 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 11:05:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:11 INFO - Successfully installed psutil 11:05:11 INFO - Cleaning up... 11:05:11 INFO - Return code: 0 11:05:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 11:05:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:05:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:11 INFO - 'HOME': '/Users/cltbld', 11:05:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:11 INFO - 'LOGNAME': 'cltbld', 11:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:11 INFO - 'MOZ_NO_REMOTE': '1', 11:05:11 INFO - 'NO_EM_RESTART': '1', 11:05:11 INFO - 'PAGER': '/bin/cat', 11:05:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:11 INFO - 'PWD': '/builds/slave/test', 11:05:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:11 INFO - 'SHELL': '/bin/bash', 11:05:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:11 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:11 INFO - 'USER': 'cltbld', 11:05:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:11 INFO - 'XPC_FLAGS': '0x0', 11:05:11 INFO - 'XPC_SERVICE_NAME': '0', 11:05:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:05:12 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 11:05:12 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 11:05:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:12 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 11:05:12 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 11:05:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:05:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:05:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:05:14 INFO - Installing collected packages: mozsystemmonitor 11:05:14 INFO - Running setup.py install for mozsystemmonitor 11:05:14 INFO - Successfully installed mozsystemmonitor 11:05:14 INFO - Cleaning up... 11:05:14 INFO - Return code: 0 11:05:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:14 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 11:05:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:05:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:14 INFO - 'HOME': '/Users/cltbld', 11:05:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:14 INFO - 'LOGNAME': 'cltbld', 11:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:14 INFO - 'MOZ_NO_REMOTE': '1', 11:05:14 INFO - 'NO_EM_RESTART': '1', 11:05:14 INFO - 'PAGER': '/bin/cat', 11:05:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:14 INFO - 'PWD': '/builds/slave/test', 11:05:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:14 INFO - 'SHELL': '/bin/bash', 11:05:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:14 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:14 INFO - 'USER': 'cltbld', 11:05:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:14 INFO - 'XPC_FLAGS': '0x0', 11:05:14 INFO - 'XPC_SERVICE_NAME': '0', 11:05:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:15 INFO - Downloading/unpacking jsonschema==2.5.1 11:05:15 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 11:05:15 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 11:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:15 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 11:05:15 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 11:05:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:05:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:05:17 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 11:05:17 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 11:05:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:17 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 11:05:17 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 11:05:17 INFO - Downloading functools32-3.2.3-2.tar.gz 11:05:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:05:17 INFO - warning: no files found matching '*.txt' 11:05:17 INFO - no previously-included directories found matching 'build' 11:05:17 INFO - no previously-included directories found matching 'dist' 11:05:17 INFO - no previously-included directories found matching '.git*' 11:05:17 INFO - Installing collected packages: jsonschema, functools32 11:05:18 INFO - Running setup.py install for functools32 11:05:18 INFO - warning: no files found matching '*.txt' 11:05:18 INFO - no previously-included directories found matching 'build' 11:05:18 INFO - no previously-included directories found matching 'dist' 11:05:18 INFO - no previously-included directories found matching '.git*' 11:05:18 INFO - Successfully installed jsonschema functools32 11:05:18 INFO - Cleaning up... 11:05:18 INFO - Return code: 0 11:05:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 11:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:05:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:18 INFO - 'HOME': '/Users/cltbld', 11:05:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:18 INFO - 'LOGNAME': 'cltbld', 11:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:18 INFO - 'MOZ_NO_REMOTE': '1', 11:05:18 INFO - 'NO_EM_RESTART': '1', 11:05:18 INFO - 'PAGER': '/bin/cat', 11:05:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:18 INFO - 'PWD': '/builds/slave/test', 11:05:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:18 INFO - 'SHELL': '/bin/bash', 11:05:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:18 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:18 INFO - 'USER': 'cltbld', 11:05:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:18 INFO - 'XPC_FLAGS': '0x0', 11:05:18 INFO - 'XPC_SERVICE_NAME': '0', 11:05:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:05:18 INFO - Cleaning up... 11:05:18 INFO - Return code: 0 11:05:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 11:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:05:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:18 INFO - 'HOME': '/Users/cltbld', 11:05:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:18 INFO - 'LOGNAME': 'cltbld', 11:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:18 INFO - 'MOZ_NO_REMOTE': '1', 11:05:18 INFO - 'NO_EM_RESTART': '1', 11:05:18 INFO - 'PAGER': '/bin/cat', 11:05:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:18 INFO - 'PWD': '/builds/slave/test', 11:05:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:18 INFO - 'SHELL': '/bin/bash', 11:05:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:18 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:18 INFO - 'USER': 'cltbld', 11:05:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:18 INFO - 'XPC_FLAGS': '0x0', 11:05:18 INFO - 'XPC_SERVICE_NAME': '0', 11:05:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:18 INFO - Downloading/unpacking blobuploader==1.2.4 11:05: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 11:05: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 11:05:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05: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 11:05: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 11:05:21 INFO - Downloading blobuploader-1.2.4.tar.gz 11:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:05:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:05:21 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 11:05:21 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 11:05:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:21 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 11:05:21 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 11:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:05:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:05:21 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 11:05:21 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 11:05:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:21 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 11:05:21 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 11:05:22 INFO - Downloading docopt-0.6.1.tar.gz 11:05:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:05:22 INFO - Installing collected packages: blobuploader, requests, docopt 11:05:22 INFO - Running setup.py install for blobuploader 11:05:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:05:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:05:22 INFO - Running setup.py install for requests 11:05:23 INFO - Running setup.py install for docopt 11:05:23 INFO - Successfully installed blobuploader requests docopt 11:05:23 INFO - Cleaning up... 11:05:23 INFO - Return code: 0 11:05:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 11:05:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:05:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:23 INFO - 'HOME': '/Users/cltbld', 11:05:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:23 INFO - 'LOGNAME': 'cltbld', 11:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:23 INFO - 'MOZ_NO_REMOTE': '1', 11:05:23 INFO - 'NO_EM_RESTART': '1', 11:05:23 INFO - 'PAGER': '/bin/cat', 11:05:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:23 INFO - 'PWD': '/builds/slave/test', 11:05:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:23 INFO - 'SHELL': '/bin/bash', 11:05:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:23 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:23 INFO - 'USER': 'cltbld', 11:05:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:23 INFO - 'XPC_FLAGS': '0x0', 11:05:23 INFO - 'XPC_SERVICE_NAME': '0', 11:05:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:23 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-KZuetc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:23 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-uldAfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:23 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-m0RSu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:23 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-ZUz0hZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:23 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-ka53am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:24 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Kr9h3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:24 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-7itZz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:24 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-nvX0Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:24 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-vVfanJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:24 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-_sv5Yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:24 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-GsNnOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:24 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-xiBmJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:24 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-IcbXir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:24 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-mL372B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:25 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-fcF2w9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:25 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-5Xor8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:25 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-PS4H8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:05:25 INFO - Running setup.py install for manifestparser 11:05:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:05:25 INFO - Running setup.py install for mozcrash 11:05:25 INFO - Running setup.py install for mozdebug 11:05:25 INFO - Running setup.py install for mozdevice 11:05:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:05:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:05:25 INFO - Running setup.py install for mozfile 11:05:26 INFO - Running setup.py install for mozhttpd 11:05:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:05:26 INFO - Running setup.py install for mozinfo 11:05:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:05:26 INFO - Running setup.py install for mozInstall 11:05:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:05:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:05:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:05:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:05:26 INFO - Running setup.py install for mozleak 11:05:26 INFO - Running setup.py install for mozlog 11:05:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:05:26 INFO - Running setup.py install for moznetwork 11:05:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:05:26 INFO - Running setup.py install for mozprocess 11:05:27 INFO - Running setup.py install for mozprofile 11:05:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:05:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:05:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:05:27 INFO - Running setup.py install for mozrunner 11:05:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:05:27 INFO - Running setup.py install for mozscreenshot 11:05:27 INFO - Running setup.py install for moztest 11:05:27 INFO - Running setup.py install for mozversion 11:05:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:05:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:05:27 INFO - Cleaning up... 11:05:27 INFO - Return code: 0 11:05:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:27 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:27 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 11:05:27 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:27 INFO - 'HOME': '/Users/cltbld', 11:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:27 INFO - 'LOGNAME': 'cltbld', 11:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:27 INFO - 'MOZ_NO_REMOTE': '1', 11:05:27 INFO - 'NO_EM_RESTART': '1', 11:05:27 INFO - 'PAGER': '/bin/cat', 11:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:27 INFO - 'PWD': '/builds/slave/test', 11:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:27 INFO - 'SHELL': '/bin/bash', 11:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:27 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:27 INFO - 'USER': 'cltbld', 11:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:27 INFO - 'XPC_FLAGS': '0x0', 11:05:27 INFO - 'XPC_SERVICE_NAME': '0', 11:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:28 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-uqwxuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:28 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)) 11:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:28 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Lw1S5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 11:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:28 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-O6pw51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:28 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)) 11:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:28 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-uT5yna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 11:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:28 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-_dHRdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:28 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)) 11:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:28 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-OzRu7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:28 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)) 11:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:28 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-5IEaGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 11:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:28 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-3rGFQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:28 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)) 11:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:28 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-YWEzxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:29 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)) 11:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:29 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-1BWHwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 11:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:29 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Jg5G2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:29 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)) 11:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:29 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-CqY9_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 11:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:29 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-EDkiGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:29 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)) 11:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:29 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-4BQA4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 11:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:29 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-AeYx2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:29 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)) 11:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:29 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-v4osoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 11:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:29 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Tn2zXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:29 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)) 11:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:05:29 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)) 11:05:29 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:05:29 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:05:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:05:29 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 11:05:29 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 11:05:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:29 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 11:05:29 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 11:05:32 INFO - Downloading blessings-1.6.tar.gz 11:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:05:32 INFO - Installing collected packages: blessings 11:05:32 INFO - Running setup.py install for blessings 11:05:32 INFO - Successfully installed blessings 11:05:32 INFO - Cleaning up... 11:05:32 INFO - Return code: 0 11:05:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:05:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:33 INFO - Reading from file tmpfile_stdout 11:05:33 INFO - Current package versions: 11:05:33 INFO - blessings == 1.6 11:05:33 INFO - blobuploader == 1.2.4 11:05:33 INFO - docopt == 0.6.1 11:05:33 INFO - functools32 == 3.2.3-2 11:05:33 INFO - jsonschema == 2.5.1 11:05:33 INFO - manifestparser == 1.1 11:05:33 INFO - mozInstall == 1.12 11:05:33 INFO - mozcrash == 0.17 11:05:33 INFO - mozdebug == 0.1 11:05:33 INFO - mozdevice == 0.48 11:05:33 INFO - mozfile == 1.2 11:05:33 INFO - mozhttpd == 0.7 11:05:33 INFO - mozinfo == 0.9 11:05:33 INFO - mozleak == 0.1 11:05:33 INFO - mozlog == 3.3 11:05:33 INFO - moznetwork == 0.27 11:05:33 INFO - mozprocess == 0.23 11:05:33 INFO - mozprofile == 0.28 11:05:33 INFO - mozrunner == 6.12 11:05:33 INFO - mozscreenshot == 0.1 11:05:33 INFO - mozsystemmonitor == 0.3 11:05:33 INFO - moztest == 0.7 11:05:33 INFO - mozversion == 1.4 11:05:33 INFO - psutil == 3.1.1 11:05:33 INFO - requests == 1.2.3 11:05:33 INFO - wsgiref == 0.1.2 11:05:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 11:05:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:05:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:33 INFO - 'HOME': '/Users/cltbld', 11:05:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:33 INFO - 'LOGNAME': 'cltbld', 11:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:33 INFO - 'MOZ_NO_REMOTE': '1', 11:05:33 INFO - 'NO_EM_RESTART': '1', 11:05:33 INFO - 'PAGER': '/bin/cat', 11:05:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:33 INFO - 'PWD': '/builds/slave/test', 11:05:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:33 INFO - 'SHELL': '/bin/bash', 11:05:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:33 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:33 INFO - 'USER': 'cltbld', 11:05:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:33 INFO - 'XPC_FLAGS': '0x0', 11:05:33 INFO - 'XPC_SERVICE_NAME': '0', 11:05:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:33 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)) 11:05:33 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)) 11:05:33 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)) 11:05:33 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)) 11:05:33 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)) 11:05:33 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)) 11:05:33 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)) 11:05:33 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)) 11:05:33 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)) 11:05:33 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)) 11:05:33 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)) 11:05:33 INFO - Cleaning up... 11:05:33 INFO - Return code: 0 11:05:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x100aa9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b3e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa43c80ce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, '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.7pKeEVq8iw/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.0U6NUrrchn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 11:05:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:05:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:05:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:05:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:33 INFO - 'HOME': '/Users/cltbld', 11:05:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:33 INFO - 'LOGNAME': 'cltbld', 11:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:33 INFO - 'MOZ_NO_REMOTE': '1', 11:05:33 INFO - 'NO_EM_RESTART': '1', 11:05:33 INFO - 'PAGER': '/bin/cat', 11:05:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:33 INFO - 'PWD': '/builds/slave/test', 11:05:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:33 INFO - 'SHELL': '/bin/bash', 11:05:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:05:33 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:05:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:33 INFO - 'USER': 'cltbld', 11:05:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:33 INFO - 'XPC_FLAGS': '0x0', 11:05:33 INFO - 'XPC_SERVICE_NAME': '0', 11:05:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:05:33 INFO - Cleaning up... 11:05:33 INFO - Return code: 0 11:05:33 INFO - Running post-action listener: _resource_record_post_action 11:05:33 INFO - Running post-action listener: _start_resource_monitoring 11:05:33 INFO - Starting resource monitoring. 11:05:33 INFO - [mozharness: 2016-09-03 18:05:33.613794Z] Finished create-virtualenv step (success) 11:05:33 INFO - [mozharness: 2016-09-03 18:05:33.614792Z] Running install step. 11:05:33 INFO - Running pre-action listener: _resource_record_pre_action 11:05:33 INFO - Running main action method: install 11:05:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:33 INFO - Reading from file tmpfile_stdout 11:05:33 INFO - Detecting whether we're running mozinstall >=1.0... 11:05:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:05:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:05:33 INFO - Reading from file tmpfile_stdout 11:05:33 INFO - Output received: 11:05:33 INFO - Usage: mozinstall [options] installer 11:05:33 INFO - Options: 11:05:33 INFO - -h, --help show this help message and exit 11:05:33 INFO - -d DEST, --destination=DEST 11:05:33 INFO - Directory to install application into. [default: 11:05:33 INFO - "/builds/slave/test"] 11:05:33 INFO - --app=APP Application being installed. [default: firefox] 11:05:33 INFO - mkdir: /builds/slave/test/build/application 11:05:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:05:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:06:08 INFO - Reading from file tmpfile_stdout 11:06:08 INFO - Output received: 11:06:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:06:08 INFO - Running post-action listener: _resource_record_post_action 11:06:08 INFO - [mozharness: 2016-09-03 18:06:08.456671Z] Finished install step (success) 11:06:08 INFO - [mozharness: 2016-09-03 18:06:08.456798Z] Running run-tests step. 11:06:08 INFO - Running pre-action listener: _resource_record_pre_action 11:06:08 INFO - Running main action method: run_tests 11:06:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:06:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:06:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:06:08 INFO - 2016-09-03 11:06:08.471 screenresolution[1940:17654] starting screenresolution argv=screenresolution get 11:06:08 INFO - 2016-09-03 11:06:08.474 screenresolution[1940:17654] Display 0: 1600x1200x32@60 11:06:08 INFO - 2016-09-03 11:06:08.479 screenresolution[1941:17656] starting screenresolution argv=screenresolution list 11:06:08 INFO - Available Modes on Display 0 11:06:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:06:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:06:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:06:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:06:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:06:08 INFO - Intel Iris: 11:06:08 INFO - Chipset Model: Intel Iris 11:06:08 INFO - Type: GPU 11:06:08 INFO - Bus: Built-In 11:06:08 INFO - VRAM (Dynamic, Max): 1536 MB 11:06:08 INFO - Vendor: Intel (0x8086) 11:06:08 INFO - Device ID: 0x0a2e 11:06:08 INFO - Revision ID: 0x0009 11:06:08 INFO - Displays: 11:06:08 INFO - Display: 11:06:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:06:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:06:08 INFO - Display Serial Number: 1600x1200 60 11:06:08 INFO - Main Display: Yes 11:06:08 INFO - Mirror: Off 11:06:08 INFO - Online: Yes 11:06:08 INFO - Rotation: Supported 11:06:08 INFO - Return code: 0 11:06:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:06:08 INFO - Python 2.7.3 11:06:08 INFO - Return code: 0 11:06:08 INFO - grabbing minidump binary from tooltool 11:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 11:06:08 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 0x100a0dd30>, '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 0x100772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a05b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:06:08 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 11:06:08 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 11:06:08 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 11:06:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:06:08 INFO - Return code: 0 11:06:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:06:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:06:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:06:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:06:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:06:08 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/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 11:06:08 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/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0230 --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 11:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7pKeEVq8iw/Render', 11:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:06:08 INFO - 'HOME': '/Users/cltbld', 11:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:06:08 INFO - 'LOGNAME': 'cltbld', 11:06:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:06:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:08 INFO - 'MOZ_NO_REMOTE': '1', 11:06:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:06:08 INFO - 'NO_EM_RESTART': '1', 11:06:08 INFO - 'PAGER': '/bin/cat', 11:06:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:08 INFO - 'PWD': '/builds/slave/test', 11:06:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:06:08 INFO - 'SHELL': '/bin/bash', 11:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0U6NUrrchn/Listeners', 11:06:08 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 11:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:06:08 INFO - 'USER': 'cltbld', 11:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:08 INFO - 'XPC_FLAGS': '0x0', 11:06:08 INFO - 'XPC_SERVICE_NAME': '0', 11:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:06:08 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/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 11:06:09 INFO - mozversion application_buildid: 20160903081850 11:06:09 INFO - mozversion application_changeset: c6906f5fc27809e2775e88a2ccc88fb794c93f4d 11:06:09 INFO - mozversion application_display_name: Nightly 11:06:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:06:09 INFO - mozversion application_name: Firefox 11:06:09 INFO - mozversion application_remotingname: firefox 11:06:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:06:09 INFO - mozversion application_vendor: Mozilla 11:06:09 INFO - mozversion application_version: 51.0a1 11:06:09 INFO - mozversion platform_buildid: 20160903081850 11:06:09 INFO - mozversion platform_changeset: c6906f5fc27809e2775e88a2ccc88fb794c93f4d 11:06:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:06:09 INFO - mozversion platform_version: 51.0a1 11:06:09 INFO - using testdate: 1472925969 11:06:09 INFO - actual date: 1472925969 11:06:09 INFO - starting webserver on 'localhost:49439' 11:06:09 INFO - SUITE-START | Running 2 tests 11:06:09 INFO - TEST-START | dromaeo_css 11:06:09 INFO - operating with platform_type : mac_ 11:06:09 INFO - Initialising browser for dromaeo_css test... 11:06:09 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpVoWVv3/profile http://localhost:49439/getInfo.html) 11:06:12 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 11:06:12 INFO - PROCESS | 1951 | colorDepth:24 11:06:12 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 11:06:12 INFO - PROCESS | 1951 | __metrics 11:06:12 ERROR - PROCESS | 1951 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 11:06:12 INFO - TEST-INFO | 1951: exit 0 11:06:12 INFO - Browser initialized. 11:06:12 INFO - Running cycle 1/1 for dromaeo_css test... 11:06:12 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpVoWVv3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:06:24 INFO - PROCESS | 1954 | RSS: Main: 245379072 11:06:24 INFO - PROCESS | 1954 | 11:07:14 INFO - PROCESS | 1954 | 1472926034388 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:09:25 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49439/tests/dromaeo/cssquery-ext.html) 11:09:27 INFO - PROCESS | 1954 | RSS: Main: 265953280 11:09:27 INFO - PROCESS | 1954 | 11:12:29 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-ext.html (next: http://localhost:49439/tests/dromaeo/cssquery-jquery.html) 11:12:30 INFO - PROCESS | 1954 | RSS: Main: 274800640 11:12:30 INFO - PROCESS | 1954 | 11:15:32 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49439/tests/dromaeo/cssquery-mootools.html) 11:15:32 INFO - PROCESS | 1954 | RSS: Main: 276500480 11:15:32 INFO - PROCESS | 1954 | 11:18:34 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49439/tests/dromaeo/cssquery-prototype.html) 11:18:35 INFO - PROCESS | 1954 | RSS: Main: 271822848 11:18:35 INFO - PROCESS | 1954 | 11:21:38 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49439/tests/dromaeo/cssquery-yui.html) 11:21:38 INFO - PROCESS | 1954 | RSS: Main: 277917696 11:21:38 INFO - PROCESS | 1954 | 11:24:42 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49439/tests/dromaeo/cssquery-yui.html (next: http://localhost:49439/tests/dromaeo/cssquery-dojo.html) 11:24:42 INFO - PROCESS | 1954 | RSS: Main: 280809472 11:24:42 INFO - PROCESS | 1954 | 11:24:42 INFO - PROCESS | 1954 | __start_tp_report 11:24:42 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 11:24:42 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 11:24:42 INFO - PROCESS | 1954 | |i|pagename|runs| 11:24:42 INFO - PROCESS | 1954 | |0;dojo.html;3308.057203696946;3326.6507016763253;3340.4655255159173;3348.614909285431;3367.8044294275546;5448.564114870811;5495.313085864267;5499.642523235992;5509.421510741375;5588.30146231721;5287.28621636079;5299.761510732017;5344.625876188668;5441.238226648263;5640.520555752766;4833.178359678863;4931.704097754128;4982.7318808623695;4994.300797888299;5038.370203724535;4917.09033828741;4932.482089380617;4955.975220123894;4957.454679985199;5007.248912663112;5035.597152227813;5063.594912406998;5080.847574572768;5086.669366491204;5138.948610513884;5032.144535428986;5065.341505604248;5070.036693028313;5080.79676812927;5105.259737338065;4929.359183306147;4975.676581022222;5054.646174767768;5088.160453525164;5125.692458452505;5448.509634132907;5461.317335333083;5500.50495455407;5527.668339899619;5576.163575463673;6000.969995149996;6050.576459647827;6066.605670631395;6072.908906366408;6093.207882975229;5969.224000879858;6070.301915279755;6145.293291271516;6150.969245153746;6166.969165154145;5595.916061259084;5672.092465205547;5672.120821272627;5726.484616384504;5734.598578099534;6153.600015998945;6212.9068063978175;6228.813135605939;6230.221222347207;6242.406971337716;4789.233722604366;5453.618246722692;5556.16657501378;5810.4189581041555;5872.618279811799;4904.583110435581;6126.295476020226;6155.569110162335;6164.815055548341;6171.691415429211;4923.409190897115;5008.69947803133;6067.969660151671;6085.269767627825;6105.725242364104;5848.181254624356;5928.762849486058;6077.088436734525;6111.755529778848;6119.632822030692;4823.493533179036;4855.101806165871;4875.049365373789;4883.51164883516;4893.681910675795;4357.7385356878685;4359.215341238544;4374.103308821684;4375.16871794358;4382.53983331752;5432.891342173213;5577.944220557823;5601.439856014366;5717.22817419643;5737.8565535861935;6146.907796383058;6148.569600128038;6162.599431036968;6165.876682466325;6170.10533472644;5634.3802181759975;5640.323161220597;5646.548276137899;5674;5679.914801277984;4688.929666055012;5075.568576671024;5237.869053273623;5300.787968481295;5308.575313974873;6202.255729312511;6224.502039836892;6225.906611400833;6236.532260080512;6254.718537665725;6140.140380346845;6152.815415537541;6162.969185154225;6184.474319682698;6187.010078387437;6134.325224225421;6159;6162.106494558354;6236;6255.655938923403;6063.060225665029;6078.787242446493;6092.329843717276;6098.32918378969;6116.816495505142;6136.355682653257;6160.476359509492;6172.598781079216;6174.166487524307;6181.320054794059;4873.561379475864;5688.288963879515;5774.047282198559;5890.204822349099;5952.970235148797;5906.173135761082;5908.763649453974;5937.3468918418075;5961.642301461926;5963.671998040149;4161.500619925508;5105.591552675852;5213.374395072616;5565.972170139124;5710.314762228393;5739.512141467855;5876.823695289148;5878.29460464747;5915.881682366415;5977.9103313451205;5852.619579727304;5855.7364918579615;5858.882822343444;5864.853378665567;5930.34766175729 11:24:42 INFO - PROCESS | 1954 | |1;ext.html;453.20688794609435;454.9499555048946;494.6339708615621;499.5029945204522;534.911739562972;36193.179546635736;38494.615053849455;38585.228295434106;38642.420363694495;38701.225975480476;38822.61177388225;39451.40822887659;39471.210575788464;39570;39641.40537891927;9534.332596718232;9725.027497250288;9726.319157658967;9738.464384458852;9746.51267436629;9615.519224038804;9676.612935482572;9679.467629280387;9716.368436051669;9721.854172187423;9718.61125554977;9719.708408747749;9720.125188733013;9743.269254805882;9758.804823903518;9680.322377433618;9680.80638387232;9694.90305096954;9703.757406064835;9730.36752611078;9599.808003839955;9652.565634546463;9661.178799801955;9668.081532254424;9707.621632699964;38109;38263.23473530514;38307.80846095779;38380.61619383798;38463;3173.904782856518;3243.886463973773;3282.2286762610765;3362.1426536233103;4075.4090656854887;29081.85459072691;30626.540601890767;30782.53826192609;30789.846050769604;30868.07395778108;3134.79623824453;3264.983675081633;3310.735141188723;3373.359061778279;3377.864885404581;441622;441824;442105;442365;446795;31589.84205079006;32430;32532.34935301328;58061.70969145127;58602.706986465644;32881.67118328834;33304.83347583246;33320.50019249713;33370.49944250838;33558.49662255068;12028.879711202952;14435.422583096768;14441.78337324941;14453.421863125568;14468.710625787426;12396.380180990915;12401.00791936643;12417.503299867903;12485.56300529477;12494.750104998031;7450.515716478411;7481.989931359197;7557.206493318233;7586.089669239726;7595.202503737028;3161.810291382478;3192.6009248843893;3199.504076868089;3208.1498402923244;3226.935461290761;886.5522910929939;968.3076600230869;969.7042402067437;1015.2385710716962;1029.8506716526063;25816.48367032649;28435.857820710764;29404.558931616044;29548.704512955024;29933.70066299309;853.7140058080613;1004.2719028704277;1026.3687831983277;1029.8506716526213;1062.3148069495132;11822.645320640395;12254.926440478263;15274.847251527342;15324.693506130037;16101.838981610033;371969;605576;606938;610387;610636;55127.724361377936;55340.723296384065;55628.72185639127;55672;55807;1232.168286406672;1336.9064165508516;1338.2840180503579;1385.7436374270792;1388.1324172392256;29321.41357172802;29511.852440737657;29593.112206633836;30169;30408.5438718415;39271.429142835106;46148.76925615351;46947;47475;47509.524904751896;533442.3327883491;536589;542355;546343;549645;24048.51902961966;25003.249902502954;25047.624285635364;25114;25167.119150829632;43191.27234910568;43290.56709432865;43420;43486.56513434825;44368.33447498226;27861.860690697227;35733.64266357303;36302.45546316754;36735.63264367322;36992;35786.463203051455;35852.64147358493;36464.453033203994;37063.25873482569;37161.62838371582;34881;35395.64604353924;35454.645453545134;35847.92456226317;35965.82017089898;30702.692973069985;32243.677563225006;32382.514262286557;32437;32438.675613243566;24140.75859241385;24526.509469810146;24673.62989555194;24682.50634987326;24863.62704559397 11:24:42 INFO - PROCESS | 1954 | |2;jquery.html;332.2259136212624;366.68465119996813;375.55684292534806;383.2583950056642;399.1459669833623;19513.41459756206;20499.38501844947;20923.790762092376;21210.257640982567;21474.46313842151;20388.796112038875;20589.38231853043;20782.68825967608;20835;21039.05324260408;5231.503007214308;5796.913046304298;5984.760609575634;6083.695815209244;6103.298120716108;6003.309619393782;6103.847403814918;6105.786297479589;6108.908366374486;6121.969390153043;5007.549320561149;5808.132381583342;5844.561657875655;5932.37710040446;5952.077427998667;5637.210790489335;5892.322382925954;5900.262467191601;5955.34491205967;5982.923073846728;5109.310243117206;5170.276161337416;5173.793048278064;5179.896402071938;5186.611004174702;17308.567285817957;22034.44913877166;24796.132135375356;24859.5028099437;24943.75281235949;2211.8341124415733;2318.9188378406675;2574.4464940037983;2717.5516039853596;3048.588440560518;15304.693906121815;15369.846301536953;15463.458778942682;15642.139682317467;15897.761533577006;1811.7463555102297;1854.8330650241408;1877.3898482993081;1887.2356695538192;1888.4523488188408;112357.87642123637;115827.42086289599;116417;117100.41449792782;117809;9192.264618830477;10158.05416429242;10192.898071019195;10532.315399499008;10931.508082136164;13555;13623.318834058258;13642.93178534101;13676.863231367559;13701.794473082911;8309.709160179365;8571.314294856396;8599.785005374915;8612.913870861337;8713.91286087131;8607.26838218746;8611.311095112376;8614.655413783505;8753.562321883881;8819.735407937773;5556.277683901067;5765.71354328029;5884.411558844081;6442.774502892376;6811.012403201508;1558.961025974337;1562.3203906300796;1565.3112630442797;1759.472158352489;1863.6179583185415;888.1873086126122;897.9461232326081;987.9061489158519;1002.0680766886866;1016.2428990402198;20656.17375304971;22119.225827096296;22196.556068878534;22211.44471388196;22221.888890555445;948.7248697877676;949.2595775295281;974.171953839231;994.3586386780486;1010.7725761703705;9786.1192492676;10251.847112106392;10573.841392379121;11617.53529858813;11633.941830290794;413815;522259;543013;555895;562549;120292.3985380085;129390;135061;135740.64259357477;135881;896.9820603587762;957.5499515227971;1009.5507499162949;1017.7913527726798;1018.4093225928943;16947.745783813007;17997.820021799613;19110.61778764445;19432.4170274892;19483.951423735387;52247.47752522426;52379;52439.73780131075;53511.46488535221;54411;75720.62139689487;76192.85710714455;76356.61821690857;76542.61728691321;77295;7648.847023059619;7759.185285544936;7792.922070779219;7884.72403465876;7898.407619428451;10131.341462804894;12191.695207619881;12431.751364972832;12447.502099915902;12558.309292988974;10079.395236285847;10540.420276884539;10739.301945373525;10778.73053173677;10815.72960675958;10397.58409663605;10405.47972601382;10525.684229473129;10731.40977246259;10758.246922715647;10620.681379558626;10996.450177490933;11002.724931876766;11206.663800086011;11209.663710088711;6627.701753421204;7590.886136707843;7658.157602663814;7836.725714599962;7879.330256928225;10465.267431279737;10598.25812193139;10767.569297228023;10938.398388088732;10971.83542246851 11:24:42 INFO - PROCESS | 1954 | |3;mootools.html;1413.7488322834292;1520.992395038025;1730.6538692261552;1847.9722804157932;1875.7561517002787;7674.731384401533;8162.8775568366445;8332.91667083329;8683.522406267653;8688.26149777269;8441.409101362908;8536.8719469208;8552.78618034548;8556.871646925301;8565.229129378355;2312.502811895438;2354.493783836475;2362.055177928825;2362.5393048355577;2362.728286247078;2323.53529294141;2352.870592117433;2368.2421625079983;2368.786809187173;2380.785729284364;2278.0773786616446;2279.293419040103;2282.2696737044153;2291.541691661666;2295.5294164696197;2334.6264597664294;2343.871087090209;2345.9178928737497;2351.2005917987794;2357.08073851199;2330.091264406866;2341.4263505441277;2345.8944347504403;2347.354477518679;2355.163916809542;7234.348908598251;8556.058833528368;8644.351673624504;8646.092160323202;8647.351448641377;4951.876203094916;5002.374703162105;5137.7944882204665;5143.974280128613;5154.278401023859;2891.845575046583;3511.7015053720465;3543.291341731664;3594.4608308753636;3603.2433189030335;1071.4963966935527;1092.2354351953663;1742.0679936234053;1806.9367572135043;1829.2957211473679;11050.447477626167;11141.220114591983;11192.832107518476;11217.439128043565;11236.66290011301;4236.152769446123;4509.27851543758;4522.5025247222775;4533.070720502289;4534.206513860061;8803.383763136519;8911.287097032222;8913.019567847532;8915.955420222857;8922.86615700765;5108.106081435734;5130.33305670268;5131.820386286462;5140.511651393186;5140.7943682253035;3574.338747331752;4606.907861842745;4745.311929770164;4790.401199850019;4805.255185446262;3632.800195989193;3692.298463291966;3775;3790.8673196437994;3809.2000679856988;1143.3997151495532;1146.9139814513944;1154.1689983212075;1164.0978241862456;1167.5271515036425;801.6673080671573;864.2265172670425;891.9598618062203;922.6263363337858;925.2597921662777;5764.510016648632;5848.502877255396;5889.087191485327;6092.5430592705725;6199.225096862892;913.0869130869131;918.1186061381148;922.4327038871046;939.178219058324;968.9951550242204;3783.6783873370523;3905.6094390561284;3935.409688546741;4005.3991901214467;4006.45912801768;9854.802903941882;9876.209903207726;10193.337433066827;10268.743281418025;10542.314749541256;10406.271560990657;10417.531211095518;10419.739506512398;10433.478326083665;10443.477826108665;6486.610803351719;7500.812479688051;7844.176361481962;7971.561564113914;8003.799905002422;5808.535317174531;5818.4763371296785;5947.137665038632;5976.043832986701;6010.699465026643;9672.177864881563;9691.07934746198;9786.41281523111;9847.409155450408;9853.852192216978;9642.71071867845;9939;10023.749406264902;10041.497925103862;10058.899411006088;6814.318568143344;7116.893246601202;7262.527935684164;7308.926910731037;7449.366803821736;8042.517448953082;8096.149904260204;8108.2297181767635;8121.187881211831;8132.227438393344;6966.094407727166;7081.504294699322;7152.141742990666;7276.163241227327;7413.740519081806;7129.39400150993;7162.606056666933;7170.2471240519;7217.314355136254;7227.421806255382;6525.3800888915475;6684.06423100776;6734.427573656294;6826.829329266808;6844.657767111525;4885.926711099408;4945.901081978413;4960.23116416956;4975.4278258000795;5037.848864534069;4292.763897985641;4681.040386720646;4858.63560232991;4990.176620857518;5003.324551185543 11:24:42 INFO - PROCESS | 1954 | |4;prototype.html;279.6381621503277;290.4177124864647;304.61922596754056;305.60271646859076;309.0681594991102;4279.379489973952;4328.826846926123;4800.351952486418;5202.661826981243;5374;4378.540253273401;4410.347083826562;4434.467863856342;5518;5523.944760552393;3936.626020528045;4134.966258435391;4159.812808423628;4176.874693759184;4198.320671731301;3260.673932606744;4007.2950758238217;4131.979340103296;4168.958310416887;4202.663786897041;3711.8144092795387;4018.819153138116;4097.774622395767;4110.856120035799;4177.707560470768;3377.7297816174646;3537.8761743338987;4085.7140000199997;4115.753054816691;4142.813573389175;3568.357695614788;3583.6203061821125;3584.534010578634;3585.7848529088337;3612.71098312137;4107.781797829169;5069.264956581312;5185.248139019859;5278.947210527884;5394.595405344576;4662.836800711959;4713.9764301178275;4734.621230301567;4741.668083234175;4748.71507709535;5386.165144402585;5390.973045134789;5732.799352022701;5749.453801888814;5765.308163020422;3601.477785721082;3602.35157671619;3720.274546463441;3852.2295540891932;3974.9006274843073;5393.352797664305;6036.788712395089;6038.456538911519;6061.818145455643;6134.570580059398;3260.9184770380457;3344.5652065231366;3698.852045918133;3756.436534519844;3946.2699400610977;3815.465834783133;3949.8025098744947;3980.203959208112;3989.940150897739;4003.5195776507007;4333.220020396296;4604.493505714368;4604.539546045368;4626.606738427204;4677.929831052537;5737.770489180386;5766.192733017381;5772.855678608068;5816.331121920949;5850.648961062265;1110.8666959964694;1273.796192609178;1312.534050412109;1324.7151862349642;1356.0643156222175;880.2210044111008;887.2414085956543;901.5987885390938;907.7458311672742;914.4696076275744;5253.684778913277;5321.09541377959;5383.192521121785;5384.730763461811;5436.266104075979;4973.328600638867;5612.719364031782;5839.182514447981;5841.831633673198;5859.509708373924;4936.925946110811;5021.422536408287;5259.368875734936;5380.273663055437;5391.16436952273;2669.3860412105323;3126.5622812806228;3127.37452509499;3132.2795756976025;3143.6699146589735;5326.9878723043275;5370.758315875795;5814.668072286174;6168.599041062316;6168.814935551941;5069.543741063322;5408.459154084639;5592.797548527086;5869.559783016205;5979;5930.547015981112;6185.84535386619;6223.408776166257;6248;6263.937360626335;5635.88728225425;5903.203067585821;5904.350521442552;5934.376890426443;6021.537615870804;5583.692896890548;5889.528837692888;5954.112255556139;5955.255593050869;5956.8808623828145;5713.771449142154;5734.197212390185;6165.660888651168;6240.469560087444;6262.624242545279;3638.199091449749;3862.2275544891695;3879.4180872869297;3893.0072831427806;3918.9804050979565;4290.656747460133;5720.971395142998;5959.67221802805;6001.459868611846;6026.578139530191;4715.575598196178;4974.865834524227;5737.74180161902;5769.740361683818;5796.246487956707;5627.296587926509;5755.798547050833;5790.768369265183;5814.505767009851;5863.325717542537;4636.768161591975;5056.494350565061;5349.919751203657;5411.161270003156;5652.1347955175515;4649.814007439664;4894.461609222912;5586.245856809279;5619;5769.624974376652;4933.210686290176;5678.545716342765;5685.51673107774;5732.850181182057;5784.50831679312 11:24:42 INFO - PROCESS | 1954 | |5;yui.html;641.3041849593192;705.8764716174664;765.4832987733279;779.8908152858601;787.3858390455447;14669.266536673164;15658.451954181577;15725.764113538306;15726.764098538531;15752.448664296751;14124.011319207659;14377;14380.209088500129;14415;14421.06263092901;653.2977049671598;810.0481933727875;826.5577915815026;840.3949156607233;841.066416277931;834.7036801935317;846.1580727176437;849.4096602861014;849.6898631999306;850.9319254459659;881.5636260051284;898.8292224477328;910.0762725833284;910.3354551177645;913.9040400757924;897.3135551303258;898.8022635020285;899.2850683706404;899.8290324838259;900.7027680865332;663.5056882622429;672.3142394757369;672.5393105722546;674.8818956682561;677.4444955136789;11806.232594881305;14490.058146220463;14508.927455362762;14591.78112328316;14638.487652932203;11482.655520334403;11574;13089.214647121107;13264.602061938158;13880.791788123186;11263.605773797877;11606.535738570446;11647.592334268345;11844.70388240301;11961.342126183057;3709.4250391189407;3742.2889650966504;3752.8498860045565;3769.792661403622;3855.248226595815;101026;103928.44107338396;106961.46519267431;107658;109025;10976.83586011918;11014.173936954761;11048.834267485994;11097.500612472459;11207.719807004727;22116.336509904406;22136.003879825446;22176.223832165797;22205.333839984825;22222.888885555793;7654.73208437702;7658.73194438192;7683.9615801921755;7883.054033516015;7915.722949696733;4128.731632443882;4243.554426785143;4264.658827293871;4299.398084268205;4304.074623955865;3446.638102999199;3552.964470355315;3566.465030245432;3573.4282514797505;3576.624454432299;581.7492391358613;591.9230500035054;654.7839213059679;668.903009063683;669.1434963247052;525.4463308321555;601.3925934805877;625.6496362037274;633.4552285034866;644.8839208942435;7475.14319010195;9263.16631503168;9275.536223188814;9368.531573421438;9425;537.6290359651828;649.4544582550585;649.6589290622461;655.9278479367266;659.5218466611764;4455.603697861534;4529.796159172779;4600.838970636079;4607.239805432133;4685.461171965199;107233;113149;152970;162360;162512.74974500443;135538.3223083898;137539;138928;139471;141140;107594.84810303907;120559;120835.79164208421;120916;121318.78681213075;8722.089413071686;8841;8850.468971861452;8856.64573417056;9049.457032578066;59043.704781477536;64795.67602161959;66232.27632787901;66392.33607664055;67169;79314.20685793068;97315.51342243527;98936;99809;101293;10331.483425828828;11879.287242765462;11909.22590031645;11984.820227696766;12162.939185304016;11831.981494534855;12284.570040048555;12779.87220127824;12948.15836970594;13343.332833358487;11717.531298747954;11917.702057448632;12216.816747748613;12227.510899564273;12309.815352769894;11823.82264266054;11889.464974076014;11969.922706956415;12260.38698065111;14164.362603682654;13436.93281533586;13572.660683482993;13755.862441375459;13814.102083364549;14050.718985620435;11118.833217501582;11219.046381057702;11288.661340159808;11610.709732256424;11781;9278.536073196448;11254.887451125383;11268.042216411697;11290.15323850698;11338.489767960627 11:24:42 INFO - PROCESS | 1954 | __end_tp_report 11:24:42 INFO - PROCESS | 1954 | __start_cc_report 11:24:42 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,386 11:24:42 INFO - PROCESS | 1954 | __end_cc_report 11:24:42 INFO - PROCESS | 1954 | __startTimestamp1472927082362__endTimestamp 11:24:42 INFO - PROCESS | 1954 | 11:24:42 INFO - PROCESS | 1954 | ------- Summary: start ------- 11:24:42 INFO - PROCESS | 1954 | Number of tests: 6 11:24:42 INFO - PROCESS | 1954 | 11:24:42 INFO - PROCESS | 1954 | [#0] dojo.html Cycles:165 Average:5547.07 Median:5688.29 stddev:650.25 (11.4%) stddev-sans-first:628.08 11:24:42 INFO - PROCESS | 1954 | Values: 3308.1 3326.7 3340.5 3348.6 3367.8 5448.6 5495.3 5499.6 5509.4 5588.3 5287.3 5299.8 5344.6 5441.2 5640.5 4833.2 4931.7 4982.7 4994.3 5038.4 4917.1 4932.5 4956.0 4957.5 5007.2 5035.6 5063.6 5080.8 5086.7 5138.9 5032.1 5065.3 5070.0 5080.8 5105.3 4929.4 4975.7 5054.6 5088.2 5125.7 5448.5 5461.3 5500.5 5527.7 5576.2 6001.0 6050.6 6066.6 6072.9 6093.2 5969.2 6070.3 6145.3 6151.0 6167.0 5595.9 5672.1 5672.1 5726.5 5734.6 6153.6 6212.9 6228.8 6230.2 6242.4 4789.2 5453.6 5556.2 5810.4 5872.6 4904.6 6126.3 6155.6 6164.8 6171.7 4923.4 5008.7 6068.0 6085.3 6105.7 5848.2 5928.8 6077.1 6111.8 6119.6 4823.5 4855.1 4875.0 4883.5 4893.7 4357.7 4359.2 4374.1 4375.2 4382.5 5432.9 5577.9 5601.4 5717.2 5737.9 6146.9 6148.6 6162.6 6165.9 6170.1 5634.4 5640.3 5646.5 5674.0 5679.9 4688.9 5075.6 5237.9 5300.8 5308.6 6202.3 6224.5 6225.9 6236.5 6254.7 6140.1 6152.8 6163.0 6184.5 6187.0 6134.3 6159.0 6162.1 6236.0 6255.7 6063.1 6078.8 6092.3 6098.3 6116.8 6136.4 6160.5 6172.6 6174.2 6181.3 4873.6 5688.3 5774.0 5890.2 5953.0 5906.2 5908.8 5937.3 5961.6 5963.7 4161.5 5105.6 5213.4 5566.0 5710.3 5739.5 5876.8 5878.3 5915.9 5977.9 5852.6 5855.7 5858.9 5864.9 5930.3 11:24:42 INFO - PROCESS | 1954 | 11:24:42 INFO - PROCESS | 1954 | [#1] ext.html Cycles:180 Average:63036.86 Median:36863.82 stddev:139313.22 (377.9%) stddev-sans-first:139624.78 11:24:42 INFO - PROCESS | 1954 | Values: 453.2 454.9 494.6 499.5 534.9 36193.2 38494.6 38585.2 38642.4 38701.2 38822.6 39451.4 39471.2 39570.0 39641.4 9534.3 9725.0 9726.3 9738.5 9746.5 9615.5 9676.6 9679.5 9716.4 9721.9 9718.6 9719.7 9720.1 9743.3 9758.8 9680.3 9680.8 9694.9 9703.8 9730.4 9599.8 9652.6 9661.2 9668.1 9707.6 38109.0 38263.2 38307.8 38380.6 38463.0 3173.9 3243.9 3282.2 3362.1 4075.4 29081.9 30626.5 30782.5 30789.8 30868.1 3134.8 3265.0 3310.7 3373.4 3377.9 441622.0 441824.0 442105.0 442365.0 446795.0 31589.8 32430.0 32532.3 58061.7 58602.7 32881.7 33304.8 33320.5 33370.5 33558.5 12028.9 14435.4 14441.8 14453.4 14468.7 12396.4 12401.0 12417.5 12485.6 12494.8 7450.5 7482.0 7557.2 7586.1 7595.2 3161.8 3192.6 3199.5 3208.1 3226.9 886.6 968.3 969.7 1015.2 1029.9 25816.5 28435.9 29404.6 29548.7 29933.7 853.7 1004.3 1026.4 1029.9 1062.3 11822.6 12254.9 15274.8 15324.7 16101.8 371969.0 605576.0 606938.0 610387.0 610636.0 55127.7 55340.7 55628.7 55672.0 55807.0 1232.2 1336.9 1338.3 1385.7 1388.1 29321.4 29511.9 29593.1 30169.0 30408.5 39271.4 46148.8 46947.0 47475.0 47509.5 533442.3 536589.0 542355.0 546343.0 549645.0 24048.5 25003.2 25047.6 25114.0 25167.1 43191.3 43290.6 43420.0 43486.6 44368.3 27861.9 35733.6 36302.5 36735.6 36992.0 35786.5 35852.6 36464.5 37063.3 37161.6 34881.0 35395.6 35454.6 35847.9 35965.8 30702.7 32243.7 32382.5 32437.0 32438.7 24140.8 24526.5 24673.6 24682.5 24863.6 11:24:42 INFO - PROCESS | 1954 | 11:24:42 INFO - PROCESS | 1954 | [#2] jquery.html Cycles:180 Average:32848.30 Median:22216.67 stddev:88015.08 (396.2%) stddev-sans-first:88228.13 11:24:42 INFO - PROCESS | 1954 | Values: 332.2 366.7 375.6 383.3 399.1 19513.4 20499.4 20923.8 21210.3 21474.5 20388.8 20589.4 20782.7 20835.0 21039.1 5231.5 5796.9 5984.8 6083.7 6103.3 6003.3 6103.8 6105.8 6108.9 6122.0 5007.5 5808.1 5844.6 5932.4 5952.1 5637.2 5892.3 5900.3 5955.3 5982.9 5109.3 5170.3 5173.8 5179.9 5186.6 17308.6 22034.4 24796.1 24859.5 24943.8 2211.8 2318.9 2574.4 2717.6 3048.6 15304.7 15369.8 15463.5 15642.1 15897.8 1811.7 1854.8 1877.4 1887.2 1888.5 112357.9 115827.4 116417.0 117100.4 117809.0 9192.3 10158.1 10192.9 10532.3 10931.5 13555.0 13623.3 13642.9 13676.9 13701.8 8309.7 8571.3 8599.8 8612.9 8713.9 8607.3 8611.3 8614.7 8753.6 8819.7 5556.3 5765.7 5884.4 6442.8 6811.0 1559.0 1562.3 1565.3 1759.5 1863.6 888.2 897.9 987.9 1002.1 1016.2 20656.2 22119.2 22196.6 22211.4 22221.9 948.7 949.3 974.2 994.4 1010.8 9786.1 10251.8 10573.8 11617.5 11633.9 413815.0 522259.0 543013.0 555895.0 562549.0 120292.4 129390.0 135061.0 135740.6 135881.0 897.0 957.5 1009.6 1017.8 1018.4 16947.7 17997.8 19110.6 19432.4 19484.0 52247.5 52379.0 52439.7 53511.5 54411.0 75720.6 76192.9 76356.6 76542.6 77295.0 7648.8 7759.2 7792.9 7884.7 7898.4 10131.3 12191.7 12431.8 12447.5 12558.3 10079.4 10540.4 10739.3 10778.7 10815.7 10397.6 10405.5 10525.7 10731.4 10758.2 10620.7 10996.5 11002.7 11206.7 11209.7 6627.7 7590.9 7658.2 7836.7 7879.3 10465.3 10598.3 10767.6 10938.4 10971.8 11:24:42 INFO - PROCESS | 1954 | 11:24:42 INFO - PROCESS | 1954 | [#3] mootools.html Cycles:180 Average:5532.68 Median:5002.85 stddev:3037.06 (60.7%) stddev-sans-first:3029.81 11:24:42 INFO - PROCESS | 1954 | Values: 1413.7 1521.0 1730.7 1848.0 1875.8 7674.7 8162.9 8332.9 8683.5 8688.3 8441.4 8536.9 8552.8 8556.9 8565.2 2312.5 2354.5 2362.1 2362.5 2362.7 2323.5 2352.9 2368.2 2368.8 2380.8 2278.1 2279.3 2282.3 2291.5 2295.5 2334.6 2343.9 2345.9 2351.2 2357.1 2330.1 2341.4 2345.9 2347.4 2355.2 7234.3 8556.1 8644.4 8646.1 8647.4 4951.9 5002.4 5137.8 5144.0 5154.3 2891.8 3511.7 3543.3 3594.5 3603.2 1071.5 1092.2 1742.1 1806.9 1829.3 11050.4 11141.2 11192.8 11217.4 11236.7 4236.2 4509.3 4522.5 4533.1 4534.2 8803.4 8911.3 8913.0 8916.0 8922.9 5108.1 5130.3 5131.8 5140.5 5140.8 3574.3 4606.9 4745.3 4790.4 4805.3 3632.8 3692.3 3775.0 3790.9 3809.2 1143.4 1146.9 1154.2 1164.1 1167.5 801.7 864.2 892.0 922.6 925.3 5764.5 5848.5 5889.1 6092.5 6199.2 913.1 918.1 922.4 939.2 969.0 3783.7 3905.6 3935.4 4005.4 4006.5 9854.8 9876.2 10193.3 10268.7 10542.3 10406.3 10417.5 10419.7 10433.5 10443.5 6486.6 7500.8 7844.2 7971.6 8003.8 5808.5 5818.5 5947.1 5976.0 6010.7 9672.2 9691.1 9786.4 9847.4 9853.9 9642.7 9939.0 10023.7 10041.5 10058.9 6814.3 7116.9 7262.5 7308.9 7449.4 8042.5 8096.1 8108.2 8121.2 8132.2 6966.1 7081.5 7152.1 7276.2 7413.7 7129.4 7162.6 7170.2 7217.3 7227.4 6525.4 6684.1 6734.4 6826.8 6844.7 4885.9 4945.9 4960.2 4975.4 5037.8 4292.8 4681.0 4858.6 4990.2 5003.3 11:24:42 INFO - PROCESS | 1954 | 11:24:42 INFO - PROCESS | 1954 | [#4] prototype.html Cycles:180 Average:4605.34 Median:5193.95 stddev:1449.03 (27.9%) stddev-sans-first:1416.26 11:24:42 INFO - PROCESS | 1954 | Values: 279.6 290.4 304.6 305.6 309.1 4279.4 4328.8 4800.4 5202.7 5374.0 4378.5 4410.3 4434.5 5518.0 5523.9 3936.6 4135.0 4159.8 4176.9 4198.3 3260.7 4007.3 4132.0 4169.0 4202.7 3711.8 4018.8 4097.8 4110.9 4177.7 3377.7 3537.9 4085.7 4115.8 4142.8 3568.4 3583.6 3584.5 3585.8 3612.7 4107.8 5069.3 5185.2 5278.9 5394.6 4662.8 4714.0 4734.6 4741.7 4748.7 5386.2 5391.0 5732.8 5749.5 5765.3 3601.5 3602.4 3720.3 3852.2 3974.9 5393.4 6036.8 6038.5 6061.8 6134.6 3260.9 3344.6 3698.9 3756.4 3946.3 3815.5 3949.8 3980.2 3989.9 4003.5 4333.2 4604.5 4604.5 4626.6 4677.9 5737.8 5766.2 5772.9 5816.3 5850.6 1110.9 1273.8 1312.5 1324.7 1356.1 880.2 887.2 901.6 907.7 914.5 5253.7 5321.1 5383.2 5384.7 5436.3 4973.3 5612.7 5839.2 5841.8 5859.5 4936.9 5021.4 5259.4 5380.3 5391.2 2669.4 3126.6 3127.4 3132.3 3143.7 5327.0 5370.8 5814.7 6168.6 6168.8 5069.5 5408.5 5592.8 5869.6 5979.0 5930.5 6185.8 6223.4 6248.0 6263.9 5635.9 5903.2 5904.4 5934.4 6021.5 5583.7 5889.5 5954.1 5955.3 5956.9 5713.8 5734.2 6165.7 6240.5 6262.6 3638.2 3862.2 3879.4 3893.0 3919.0 4290.7 5721.0 5959.7 6001.5 6026.6 4715.6 4974.9 5737.7 5769.7 5796.2 5627.3 5755.8 5790.8 5814.5 5863.3 4636.8 5056.5 5349.9 5411.2 5652.1 4649.8 4894.5 5586.2 5619.0 5769.6 4933.2 5678.5 5685.5 5732.9 5784.5 11:24:42 INFO - PROCESS | 1954 | 11:24:42 INFO - PROCESS | 1954 | [#5] yui.html Cycles:180 Average:24912.72 Median:3499.80 stddev:40379.48 (1153.8%) stddev-sans-first:40451.63 11:24:42 INFO - PROCESS | 1954 | Values: 641.3 705.9 765.5 779.9 787.4 14669.3 15658.5 15725.8 15726.8 15752.4 14124.0 14377.0 14380.2 14415.0 14421.1 653.3 810.0 826.6 840.4 841.1 834.7 846.2 849.4 849.7 850.9 881.6 898.8 910.1 910.3 913.9 897.3 898.8 899.3 899.8 900.7 663.5 672.3 672.5 674.9 677.4 11806.2 14490.1 14508.9 14591.8 14638.5 11482.7 11574.0 13089.2 13264.6 13880.8 11263.6 11606.5 11647.6 11844.7 11961.3 3709.4 3742.3 3752.8 3769.8 3855.2 101026.0 103928.4 106961.5 107658.0 109025.0 10976.8 11014.2 11048.8 11097.5 11207.7 22116.3 22136.0 22176.2 22205.3 22222.9 7654.7 7658.7 7684.0 7883.1 7915.7 4128.7 4243.6 4264.7 4299.4 4304.1 3446.6 3553.0 3566.5 3573.4 3576.6 581.7 591.9 654.8 668.9 669.1 525.4 601.4 625.6 633.5 644.9 7475.1 9263.2 9275.5 9368.5 9425.0 537.6 649.5 649.7 655.9 659.5 4455.6 4529.8 4600.8 4607.2 4685.5 107233.0 113149.0 152970.0 162360.0 162512.7 135538.3 137539.0 138928.0 139471.0 141140.0 107594.8 120559.0 120835.8 120916.0 121318.8 8722.1 8841.0 8850.5 8856.6 9049.5 59043.7 64795.7 66232.3 66392.3 67169.0 79314.2 97315.5 98936.0 99809.0 101293.0 10331.5 11879.3 11909.2 11984.8 12162.9 11832.0 12284.6 12779.9 12948.2 13343.3 11717.5 11917.7 12216.8 12227.5 12309.8 11823.8 11889.5 11969.9 12260.4 14164.4 13436.9 13572.7 13755.9 13814.1 14050.7 11118.8 11219.0 11288.7 11610.7 11781.0 9278.5 11254.9 11268.0 11290.2 11338.5 11:24:42 INFO - PROCESS | 1954 | -------- Summary: end -------- 11:24:42 INFO - PROCESS | 1954 | 11:24:43 INFO - TEST-INFO | 1954: exit 0 11:24:43 INFO - TEST-OK | dromaeo_css | took 1114056ms 11:24:43 INFO - TEST-START | kraken 11:24:43 INFO - operating with platform_type : mac_ 11:24:43 INFO - Initialising browser for kraken test... 11:24:43 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpHyj4kb/profile http://localhost:49439/getInfo.html) 11:24:46 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 11:24:46 INFO - PROCESS | 1958 | colorDepth:24 11:24:46 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 11:24:46 INFO - PROCESS | 1958 | __metrics 11:24:46 ERROR - PROCESS | 1958 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 11:24:46 INFO - TEST-INFO | 1958: exit 0 11:24:46 INFO - Browser initialized. 11:24:46 INFO - Running cycle 1/1 for kraken test... 11:24:46 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpHyj4kb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:24:58 INFO - PROCESS | 1960 | RSS: Main: 245497856 11:24:58 INFO - PROCESS | 1960 | 11:25:01 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49439/tests/kraken/driver.html?testName=audio-beat-detection) 11:25:02 INFO - PROCESS | 1960 | RSS: Main: 258113536 11:25:02 INFO - PROCESS | 1960 | 11:25:05 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49439/tests/kraken/driver.html?testName=audio-dft) 11:25:06 INFO - PROCESS | 1960 | RSS: Main: 266874880 11:25:06 INFO - PROCESS | 1960 | 11:25:09 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49439/tests/kraken/driver.html?testName=audio-fft) 11:25:10 INFO - PROCESS | 1960 | RSS: Main: 263663616 11:25:10 INFO - PROCESS | 1960 | 11:25:12 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49439/tests/kraken/driver.html?testName=audio-oscillator) 11:25:12 INFO - PROCESS | 1960 | RSS: Main: 264826880 11:25:12 INFO - PROCESS | 1960 | 11:25:14 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49439/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:25:15 INFO - PROCESS | 1960 | RSS: Main: 265367552 11:25:15 INFO - PROCESS | 1960 | 11:25:18 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49439/tests/kraken/driver.html?testName=imaging-darkroom) 11:25:18 INFO - PROCESS | 1960 | RSS: Main: 265936896 11:25:18 INFO - PROCESS | 1960 | 11:25:21 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49439/tests/kraken/driver.html?testName=imaging-desaturate) 11:25:22 INFO - PROCESS | 1960 | RSS: Main: 267460608 11:25:22 INFO - PROCESS | 1960 | 11:25:25 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49439/tests/kraken/driver.html?testName=json-parse-financial) 11:25:26 INFO - PROCESS | 1960 | RSS: Main: 267620352 11:25:26 INFO - PROCESS | 1960 | 11:25:27 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49439/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:25:28 INFO - PROCESS | 1960 | RSS: Main: 266694656 11:25:28 INFO - PROCESS | 1960 | 11:25:30 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:25:30 INFO - PROCESS | 1960 | RSS: Main: 266874880 11:25:30 INFO - PROCESS | 1960 | 11:25:33 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:25:33 INFO - PROCESS | 1960 | RSS: Main: 267214848 11:25:33 INFO - PROCESS | 1960 | 11:25:36 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:25:37 INFO - PROCESS | 1960 | RSS: Main: 267550720 11:25:37 INFO - PROCESS | 1960 | 11:25:40 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:25:41 INFO - PROCESS | 1960 | RSS: Main: 268615680 11:25:41 INFO - PROCESS | 1960 | 11:25:42 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:49439/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49439/tests/kraken/driver.html?testName=ai-astar) 11:25:43 INFO - PROCESS | 1960 | RSS: Main: 270237696 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | __start_tp_report 11:25:43 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 11:25:43 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 11:25:43 INFO - PROCESS | 1960 | |i|pagename|runs| 11:25:43 INFO - PROCESS | 1960 | |0;ai-astar;117;112;141;171;116;101;115;96;172;175 11:25:43 INFO - PROCESS | 1960 | |1;audio-beat-detection;146;112;149;173;97;141;166;143;177;109 11:25:43 INFO - PROCESS | 1960 | |2;audio-dft;195;214;234;210;203;209;237;202;201;192 11:25:43 INFO - PROCESS | 1960 | |3;audio-fft;67;83;81;177;62;61;59;68;165;66 11:25:43 INFO - PROCESS | 1960 | |4;audio-oscillator;70;75;132;69;77;84;114;70;72;113 11:25:43 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;89;91;92;74;120;130;95;90;125;89 11:25:43 INFO - PROCESS | 1960 | |6;imaging-darkroom;115;156;120;97;96;96;96;98;95;98 11:25:43 INFO - PROCESS | 1960 | |7;imaging-desaturate;100;94;92;91;129;151;109;109;149;80 11:25:43 INFO - PROCESS | 1960 | |8;json-parse-financial;56;58;57;59;58;58;55;56;54;57 11:25:43 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;72;69;77;69;86;78;74;70;74;74 11:25:43 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;88;91;86;86;89;88;88;93;92;87 11:25:43 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;138;174;170;147;141;164;169;140;182;141 11:25:43 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;179;201;251;198;182;177;178;180;178;178 11:25:43 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;71;72;68;70;71;69;71;70;80;71 11:25:43 INFO - PROCESS | 1960 | __end_tp_report 11:25:43 INFO - PROCESS | 1960 | __start_cc_report 11:25:43 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,967 11:25:43 INFO - PROCESS | 1960 | __end_cc_report 11:25:43 INFO - PROCESS | 1960 | __startTimestamp1472927143111__endTimestamp 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | ------- Summary: start ------- 11:25:43 INFO - PROCESS | 1960 | Number of tests: 14 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:131.60 Median:156.00 stddev:30.69 (19.7%) stddev-sans-first:32.09 11:25:43 INFO - PROCESS | 1960 | Values: 117.0 112.0 141.0 171.0 116.0 101.0 115.0 96.0 172.0 175.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:157.50 stddev:27.56 (17.5%) stddev-sans-first:29.18 11:25:43 INFO - PROCESS | 1960 | Values: 146.0 112.0 149.0 173.0 97.0 141.0 166.0 143.0 177.0 109.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:209.70 Median:209.50 stddev:15.14 (7.2%) stddev-sans-first:15.10 11:25:43 INFO - PROCESS | 1960 | Values: 195.0 214.0 234.0 210.0 203.0 209.0 237.0 202.0 201.0 192.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:88.90 Median:66.50 stddev:44.08 (66.3%) stddev-sans-first:46.04 11:25:43 INFO - PROCESS | 1960 | Values: 67.0 83.0 81.0 177.0 62.0 61.0 59.0 68.0 165.0 66.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:87.60 Median:71.00 stddev:23.11 (32.5%) stddev-sans-first:23.62 11:25:43 INFO - PROCESS | 1960 | Values: 70.0 75.0 132.0 69.0 77.0 84.0 114.0 70.0 72.0 113.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:99.50 Median:89.50 stddev:18.59 (20.8%) stddev-sans-first:19.33 11:25:43 INFO - PROCESS | 1960 | Values: 89.0 91.0 92.0 74.0 120.0 130.0 95.0 90.0 125.0 89.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:96.00 stddev:19.44 (20.3%) stddev-sans-first:20.39 11:25:43 INFO - PROCESS | 1960 | Values: 115.0 156.0 120.0 97.0 96.0 96.0 96.0 98.0 95.0 98.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:110.40 Median:115.50 stddev:24.73 (21.4%) stddev-sans-first:25.94 11:25:43 INFO - PROCESS | 1960 | Values: 100.0 94.0 92.0 91.0 129.0 151.0 109.0 109.0 149.0 80.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:57.50 stddev:1.55 (2.7%) stddev-sans-first:1.62 11:25:43 INFO - PROCESS | 1960 | Values: 56.0 58.0 57.0 59.0 58.0 58.0 55.0 56.0 54.0 57.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:74.30 Median:74.00 stddev:5.14 (7.0%) stddev-sans-first:5.39 11:25:43 INFO - PROCESS | 1960 | Values: 72.0 69.0 77.0 69.0 86.0 78.0 74.0 70.0 74.0 74.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:88.50 stddev:2.44 (2.8%) stddev-sans-first:2.57 11:25:43 INFO - PROCESS | 1960 | Values: 88.0 91.0 86.0 86.0 89.0 88.0 88.0 93.0 92.0 87.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:156.60 Median:166.50 stddev:16.79 (10.1%) stddev-sans-first:16.40 11:25:43 INFO - PROCESS | 1960 | Values: 138.0 174.0 170.0 147.0 141.0 164.0 169.0 140.0 182.0 141.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.20 Median:181.00 stddev:23.07 (12.7%) stddev-sans-first:24.11 11:25:43 INFO - PROCESS | 1960 | Values: 179.0 201.0 251.0 198.0 182.0 177.0 178.0 180.0 178.0 178.0 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:71.00 stddev:3.27 (4.6%) stddev-sans-first:3.46 11:25:43 INFO - PROCESS | 1960 | Values: 71.0 72.0 68.0 70.0 71.0 69.0 71.0 70.0 80.0 71.0 11:25:43 INFO - PROCESS | 1960 | -------- Summary: end -------- 11:25:43 INFO - PROCESS | 1960 | 11:25:43 INFO - TEST-INFO | 1960: exit 0 11:25:43 INFO - TEST-OK | kraken | took 60812ms 11:25:43 INFO - SUITE-END | took 1174s 11:25:44 INFO - Completed test suite (00:19:35) 11:25:44 INFO - javascript benchmark 11:25:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7152.737153772871, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.057203696946, 3326.6507016763253, 3340.4655255159173, 3348.614909285431, 3367.8044294275546, 5448.564114870811, 5495.313085864267, 5499.642523235992, 5509.421510741375, 5588.30146231721, 5287.28621636079, 5299.761510732017, 5344.625876188668, 5441.238226648263, 5640.520555752766, 4833.178359678863, 4931.704097754128, 4982.7318808623695, 4994.300797888299, 5038.370203724535, 4917.09033828741, 4932.482089380617, 4955.975220123894, 4957.454679985199, 5007.248912663112, 5035.597152227813, 5063.594912406998, 5080.847574572768, 5086.669366491204, 5138.948610513884, 5032.144535428986, 5065.341505604248, 5070.036693028313, 5080.79676812927, 5105.259737338065, 4929.359183306147, 4975.676581022222, 5054.646174767768, 5088.160453525164, 5125.692458452505, 5448.509634132907, 5461.317335333083, 5500.50495455407, 5527.668339899619, 5576.163575463673, 6000.969995149996, 6050.576459647827, 6066.605670631395, 6072.908906366408, 6093.207882975229, 5969.224000879858, 6070.301915279755, 6145.293291271516, 6150.969245153746, 6166.969165154145, 5595.916061259084, 5672.092465205547, 5672.120821272627, 5726.484616384504, 5734.598578099534, 6153.600015998945, 6212.9068063978175, 6228.813135605939, 6230.221222347207, 6242.406971337716, 4789.233722604366, 5453.618246722692, 5556.16657501378, 5810.4189581041555, 5872.618279811799, 4904.583110435581, 6126.295476020226, 6155.569110162335, 6164.815055548341, 6171.691415429211, 4923.409190897115, 5008.69947803133, 6067.969660151671, 6085.269767627825, 6105.725242364104, 5848.181254624356, 5928.762849486058, 6077.088436734525, 6111.755529778848, 6119.632822030692, 4823.493533179036, 4855.101806165871, 4875.049365373789, 4883.51164883516, 4893.681910675795, 4357.7385356878685, 4359.215341238544, 4374.103308821684, 4375.16871794358, 4382.53983331752, 5432.891342173213, 5577.944220557823, 5601.439856014366, 5717.22817419643, 5737.8565535861935, 6146.907796383058, 6148.569600128038, 6162.599431036968, 6165.876682466325, 6170.10533472644, 5634.3802181759975, 5640.323161220597, 5646.548276137899, 5674.0, 5679.914801277984, 4688.929666055012, 5075.568576671024, 5237.869053273623, 5300.787968481295, 5308.575313974873, 6202.255729312511, 6224.502039836892, 6225.906611400833, 6236.532260080512, 6254.718537665725, 6140.140380346845, 6152.815415537541, 6162.969185154225, 6184.474319682698, 6187.010078387437, 6134.325224225421, 6159.0, 6162.106494558354, 6236.0, 6255.655938923403, 6063.060225665029, 6078.787242446493, 6092.329843717276, 6098.32918378969, 6116.816495505142, 6136.355682653257, 6160.476359509492, 6172.598781079216, 6174.166487524307, 6181.320054794059, 4873.561379475864, 5688.288963879515, 5774.047282198559, 5890.204822349099, 5952.970235148797, 5906.173135761082, 5908.763649453974, 5937.3468918418075, 5961.642301461926, 5963.671998040149, 4161.500619925508, 5105.591552675852, 5213.374395072616, 5565.972170139124, 5710.314762228393, 5739.512141467855, 5876.823695289148, 5878.29460464747, 5915.881682366415, 5977.9103313451205, 5852.619579727304, 5855.7364918579615, 5858.882822343444, 5864.853378665567, 5930.34766175729], "value": 5507.378930192431, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.20688794609435, 454.9499555048946, 494.6339708615621, 499.5029945204522, 534.911739562972, 36193.179546635736, 38494.615053849455, 38585.228295434106, 38642.420363694495, 38701.225975480476, 38822.61177388225, 39451.40822887659, 39471.210575788464, 39570.0, 39641.40537891927, 9534.332596718232, 9725.027497250288, 9726.319157658967, 9738.464384458852, 9746.51267436629, 9615.519224038804, 9676.612935482572, 9679.467629280387, 9716.368436051669, 9721.854172187423, 9718.61125554977, 9719.708408747749, 9720.125188733013, 9743.269254805882, 9758.804823903518, 9680.322377433618, 9680.80638387232, 9694.90305096954, 9703.757406064835, 9730.36752611078, 9599.808003839955, 9652.565634546463, 9661.178799801955, 9668.081532254424, 9707.621632699964, 38109.0, 38263.23473530514, 38307.80846095779, 38380.61619383798, 38463.0, 3173.904782856518, 3243.886463973773, 3282.2286762610765, 3362.1426536233103, 4075.4090656854887, 29081.85459072691, 30626.540601890767, 30782.53826192609, 30789.846050769604, 30868.07395778108, 3134.79623824453, 3264.983675081633, 3310.735141188723, 3373.359061778279, 3377.864885404581, 441622.0, 441824.0, 442105.0, 442365.0, 446795.0, 31589.84205079006, 32430.0, 32532.34935301328, 58061.70969145127, 58602.706986465644, 32881.67118328834, 33304.83347583246, 33320.50019249713, 33370.49944250838, 33558.49662255068, 12028.879711202952, 14435.422583096768, 14441.78337324941, 14453.421863125568, 14468.710625787426, 12396.380180990915, 12401.00791936643, 12417.503299867903, 12485.56300529477, 12494.750104998031, 7450.515716478411, 7481.989931359197, 7557.206493318233, 7586.089669239726, 7595.202503737028, 3161.810291382478, 3192.6009248843893, 3199.504076868089, 3208.1498402923244, 3226.935461290761, 886.5522910929939, 968.3076600230869, 969.7042402067437, 1015.2385710716962, 1029.8506716526063, 25816.48367032649, 28435.857820710764, 29404.558931616044, 29548.704512955024, 29933.70066299309, 853.7140058080613, 1004.2719028704277, 1026.3687831983277, 1029.8506716526213, 1062.3148069495132, 11822.645320640395, 12254.926440478263, 15274.847251527342, 15324.693506130037, 16101.838981610033, 371969.0, 605576.0, 606938.0, 610387.0, 610636.0, 55127.724361377936, 55340.723296384065, 55628.72185639127, 55672.0, 55807.0, 1232.168286406672, 1336.9064165508516, 1338.2840180503579, 1385.7436374270792, 1388.1324172392256, 29321.41357172802, 29511.852440737657, 29593.112206633836, 30169.0, 30408.5438718415, 39271.429142835106, 46148.76925615351, 46947.0, 47475.0, 47509.524904751896, 533442.3327883491, 536589.0, 542355.0, 546343.0, 549645.0, 24048.51902961966, 25003.249902502954, 25047.624285635364, 25114.0, 25167.119150829632, 43191.27234910568, 43290.56709432865, 43420.0, 43486.56513434825, 44368.33447498226, 27861.860690697227, 35733.64266357303, 36302.45546316754, 36735.63264367322, 36992.0, 35786.463203051455, 35852.64147358493, 36464.453033203994, 37063.25873482569, 37161.62838371582, 34881.0, 35395.64604353924, 35454.645453545134, 35847.92456226317, 35965.82017089898, 30702.692973069985, 32243.677563225006, 32382.514262286557, 32437.0, 32438.675613243566, 24140.75859241385, 24526.509469810146, 24673.62989555194, 24682.50634987326, 24863.62704559397], "value": 17784.323242602844, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.2259136212624, 366.68465119996813, 375.55684292534806, 383.2583950056642, 399.1459669833623, 19513.41459756206, 20499.38501844947, 20923.790762092376, 21210.257640982567, 21474.46313842151, 20388.796112038875, 20589.38231853043, 20782.68825967608, 20835.0, 21039.05324260408, 5231.503007214308, 5796.913046304298, 5984.760609575634, 6083.695815209244, 6103.298120716108, 6003.309619393782, 6103.847403814918, 6105.786297479589, 6108.908366374486, 6121.969390153043, 5007.549320561149, 5808.132381583342, 5844.561657875655, 5932.37710040446, 5952.077427998667, 5637.210790489335, 5892.322382925954, 5900.262467191601, 5955.34491205967, 5982.923073846728, 5109.310243117206, 5170.276161337416, 5173.793048278064, 5179.896402071938, 5186.611004174702, 17308.567285817957, 22034.44913877166, 24796.132135375356, 24859.5028099437, 24943.75281235949, 2211.8341124415733, 2318.9188378406675, 2574.4464940037983, 2717.5516039853596, 3048.588440560518, 15304.693906121815, 15369.846301536953, 15463.458778942682, 15642.139682317467, 15897.761533577006, 1811.7463555102297, 1854.8330650241408, 1877.3898482993081, 1887.2356695538192, 1888.4523488188408, 112357.87642123637, 115827.42086289599, 116417.0, 117100.41449792782, 117809.0, 9192.264618830477, 10158.05416429242, 10192.898071019195, 10532.315399499008, 10931.508082136164, 13555.0, 13623.318834058258, 13642.93178534101, 13676.863231367559, 13701.794473082911, 8309.709160179365, 8571.314294856396, 8599.785005374915, 8612.913870861337, 8713.91286087131, 8607.26838218746, 8611.311095112376, 8614.655413783505, 8753.562321883881, 8819.735407937773, 5556.277683901067, 5765.71354328029, 5884.411558844081, 6442.774502892376, 6811.012403201508, 1558.961025974337, 1562.3203906300796, 1565.3112630442797, 1759.472158352489, 1863.6179583185415, 888.1873086126122, 897.9461232326081, 987.9061489158519, 1002.0680766886866, 1016.2428990402198, 20656.17375304971, 22119.225827096296, 22196.556068878534, 22211.44471388196, 22221.888890555445, 948.7248697877676, 949.2595775295281, 974.171953839231, 994.3586386780486, 1010.7725761703705, 9786.1192492676, 10251.847112106392, 10573.841392379121, 11617.53529858813, 11633.941830290794, 413815.0, 522259.0, 543013.0, 555895.0, 562549.0, 120292.3985380085, 129390.0, 135061.0, 135740.64259357477, 135881.0, 896.9820603587762, 957.5499515227971, 1009.5507499162949, 1017.7913527726798, 1018.4093225928943, 16947.745783813007, 17997.820021799613, 19110.61778764445, 19432.4170274892, 19483.951423735387, 52247.47752522426, 52379.0, 52439.73780131075, 53511.46488535221, 54411.0, 75720.62139689487, 76192.85710714455, 76356.61821690857, 76542.61728691321, 77295.0, 7648.847023059619, 7759.185285544936, 7792.922070779219, 7884.72403465876, 7898.407619428451, 10131.341462804894, 12191.695207619881, 12431.751364972832, 12447.502099915902, 12558.309292988974, 10079.395236285847, 10540.420276884539, 10739.301945373525, 10778.73053173677, 10815.72960675958, 10397.58409663605, 10405.47972601382, 10525.684229473129, 10731.40977246259, 10758.246922715647, 10620.681379558626, 10996.450177490933, 11002.724931876766, 11206.663800086011, 11209.663710088711, 6627.701753421204, 7590.886136707843, 7658.157602663814, 7836.725714599962, 7879.330256928225, 10465.267431279737, 10598.25812193139, 10767.569297228023, 10938.398388088732, 10971.83542246851], "value": 9575.248078180133, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.7488322834292, 1520.992395038025, 1730.6538692261552, 1847.9722804157932, 1875.7561517002787, 7674.731384401533, 8162.8775568366445, 8332.91667083329, 8683.522406267653, 8688.26149777269, 8441.409101362908, 8536.8719469208, 8552.78618034548, 8556.871646925301, 8565.229129378355, 2312.502811895438, 2354.493783836475, 2362.055177928825, 2362.5393048355577, 2362.728286247078, 2323.53529294141, 2352.870592117433, 2368.2421625079983, 2368.786809187173, 2380.785729284364, 2278.0773786616446, 2279.293419040103, 2282.2696737044153, 2291.541691661666, 2295.5294164696197, 2334.6264597664294, 2343.871087090209, 2345.9178928737497, 2351.2005917987794, 2357.08073851199, 2330.091264406866, 2341.4263505441277, 2345.8944347504403, 2347.354477518679, 2355.163916809542, 7234.348908598251, 8556.058833528368, 8644.351673624504, 8646.092160323202, 8647.351448641377, 4951.876203094916, 5002.374703162105, 5137.7944882204665, 5143.974280128613, 5154.278401023859, 2891.845575046583, 3511.7015053720465, 3543.291341731664, 3594.4608308753636, 3603.2433189030335, 1071.4963966935527, 1092.2354351953663, 1742.0679936234053, 1806.9367572135043, 1829.2957211473679, 11050.447477626167, 11141.220114591983, 11192.832107518476, 11217.439128043565, 11236.66290011301, 4236.152769446123, 4509.27851543758, 4522.5025247222775, 4533.070720502289, 4534.206513860061, 8803.383763136519, 8911.287097032222, 8913.019567847532, 8915.955420222857, 8922.86615700765, 5108.106081435734, 5130.33305670268, 5131.820386286462, 5140.511651393186, 5140.7943682253035, 3574.338747331752, 4606.907861842745, 4745.311929770164, 4790.401199850019, 4805.255185446262, 3632.800195989193, 3692.298463291966, 3775.0, 3790.8673196437994, 3809.2000679856988, 1143.3997151495532, 1146.9139814513944, 1154.1689983212075, 1164.0978241862456, 1167.5271515036425, 801.6673080671573, 864.2265172670425, 891.9598618062203, 922.6263363337858, 925.2597921662777, 5764.510016648632, 5848.502877255396, 5889.087191485327, 6092.5430592705725, 6199.225096862892, 913.0869130869131, 918.1186061381148, 922.4327038871046, 939.178219058324, 968.9951550242204, 3783.6783873370523, 3905.6094390561284, 3935.409688546741, 4005.3991901214467, 4006.45912801768, 9854.802903941882, 9876.209903207726, 10193.337433066827, 10268.743281418025, 10542.314749541256, 10406.271560990657, 10417.531211095518, 10419.739506512398, 10433.478326083665, 10443.477826108665, 6486.610803351719, 7500.812479688051, 7844.176361481962, 7971.561564113914, 8003.799905002422, 5808.535317174531, 5818.4763371296785, 5947.137665038632, 5976.043832986701, 6010.699465026643, 9672.177864881563, 9691.07934746198, 9786.41281523111, 9847.409155450408, 9853.852192216978, 9642.71071867845, 9939.0, 10023.749406264902, 10041.497925103862, 10058.899411006088, 6814.318568143344, 7116.893246601202, 7262.527935684164, 7308.926910731037, 7449.366803821736, 8042.517448953082, 8096.149904260204, 8108.2297181767635, 8121.187881211831, 8132.227438393344, 6966.094407727166, 7081.504294699322, 7152.141742990666, 7276.163241227327, 7413.740519081806, 7129.39400150993, 7162.606056666933, 7170.2471240519, 7217.314355136254, 7227.421806255382, 6525.3800888915475, 6684.06423100776, 6734.427573656294, 6826.829329266808, 6844.657767111525, 4885.926711099408, 4945.901081978413, 4960.23116416956, 4975.4278258000795, 5037.848864534069, 4292.763897985641, 4681.040386720646, 4858.63560232991, 4990.176620857518, 5003.324551185543], "value": 4504.563058562622, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.6381621503277, 290.4177124864647, 304.61922596754056, 305.60271646859076, 309.0681594991102, 4279.379489973952, 4328.826846926123, 4800.351952486418, 5202.661826981243, 5374.0, 4378.540253273401, 4410.347083826562, 4434.467863856342, 5518.0, 5523.944760552393, 3936.626020528045, 4134.966258435391, 4159.812808423628, 4176.874693759184, 4198.320671731301, 3260.673932606744, 4007.2950758238217, 4131.979340103296, 4168.958310416887, 4202.663786897041, 3711.8144092795387, 4018.819153138116, 4097.774622395767, 4110.856120035799, 4177.707560470768, 3377.7297816174646, 3537.8761743338987, 4085.7140000199997, 4115.753054816691, 4142.813573389175, 3568.357695614788, 3583.6203061821125, 3584.534010578634, 3585.7848529088337, 3612.71098312137, 4107.781797829169, 5069.264956581312, 5185.248139019859, 5278.947210527884, 5394.595405344576, 4662.836800711959, 4713.9764301178275, 4734.621230301567, 4741.668083234175, 4748.71507709535, 5386.165144402585, 5390.973045134789, 5732.799352022701, 5749.453801888814, 5765.308163020422, 3601.477785721082, 3602.35157671619, 3720.274546463441, 3852.2295540891932, 3974.9006274843073, 5393.352797664305, 6036.788712395089, 6038.456538911519, 6061.818145455643, 6134.570580059398, 3260.9184770380457, 3344.5652065231366, 3698.852045918133, 3756.436534519844, 3946.2699400610977, 3815.465834783133, 3949.8025098744947, 3980.203959208112, 3989.940150897739, 4003.5195776507007, 4333.220020396296, 4604.493505714368, 4604.539546045368, 4626.606738427204, 4677.929831052537, 5737.770489180386, 5766.192733017381, 5772.855678608068, 5816.331121920949, 5850.648961062265, 1110.8666959964694, 1273.796192609178, 1312.534050412109, 1324.7151862349642, 1356.0643156222175, 880.2210044111008, 887.2414085956543, 901.5987885390938, 907.7458311672742, 914.4696076275744, 5253.684778913277, 5321.09541377959, 5383.192521121785, 5384.730763461811, 5436.266104075979, 4973.328600638867, 5612.719364031782, 5839.182514447981, 5841.831633673198, 5859.509708373924, 4936.925946110811, 5021.422536408287, 5259.368875734936, 5380.273663055437, 5391.16436952273, 2669.3860412105323, 3126.5622812806228, 3127.37452509499, 3132.2795756976025, 3143.6699146589735, 5326.9878723043275, 5370.758315875795, 5814.668072286174, 6168.599041062316, 6168.814935551941, 5069.543741063322, 5408.459154084639, 5592.797548527086, 5869.559783016205, 5979.0, 5930.547015981112, 6185.84535386619, 6223.408776166257, 6248.0, 6263.937360626335, 5635.88728225425, 5903.203067585821, 5904.350521442552, 5934.376890426443, 6021.537615870804, 5583.692896890548, 5889.528837692888, 5954.112255556139, 5955.255593050869, 5956.8808623828145, 5713.771449142154, 5734.197212390185, 6165.660888651168, 6240.469560087444, 6262.624242545279, 3638.199091449749, 3862.2275544891695, 3879.4180872869297, 3893.0072831427806, 3918.9804050979565, 4290.656747460133, 5720.971395142998, 5959.67221802805, 6001.459868611846, 6026.578139530191, 4715.575598196178, 4974.865834524227, 5737.74180161902, 5769.740361683818, 5796.246487956707, 5627.296587926509, 5755.798547050833, 5790.768369265183, 5814.505767009851, 5863.325717542537, 4636.768161591975, 5056.494350565061, 5349.919751203657, 5411.161270003156, 5652.1347955175515, 4649.814007439664, 4894.461609222912, 5586.245856809279, 5619.0, 5769.624974376652, 4933.210686290176, 5678.545716342765, 5685.51673107774, 5732.850181182057, 5784.50831679312], "value": 4139.661444401976, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.3041849593192, 705.8764716174664, 765.4832987733279, 779.8908152858601, 787.3858390455447, 14669.266536673164, 15658.451954181577, 15725.764113538306, 15726.764098538531, 15752.448664296751, 14124.011319207659, 14377.0, 14380.209088500129, 14415.0, 14421.06263092901, 653.2977049671598, 810.0481933727875, 826.5577915815026, 840.3949156607233, 841.066416277931, 834.7036801935317, 846.1580727176437, 849.4096602861014, 849.6898631999306, 850.9319254459659, 881.5636260051284, 898.8292224477328, 910.0762725833284, 910.3354551177645, 913.9040400757924, 897.3135551303258, 898.8022635020285, 899.2850683706404, 899.8290324838259, 900.7027680865332, 663.5056882622429, 672.3142394757369, 672.5393105722546, 674.8818956682561, 677.4444955136789, 11806.232594881305, 14490.058146220463, 14508.927455362762, 14591.78112328316, 14638.487652932203, 11482.655520334403, 11574.0, 13089.214647121107, 13264.602061938158, 13880.791788123186, 11263.605773797877, 11606.535738570446, 11647.592334268345, 11844.70388240301, 11961.342126183057, 3709.4250391189407, 3742.2889650966504, 3752.8498860045565, 3769.792661403622, 3855.248226595815, 101026.0, 103928.44107338396, 106961.46519267431, 107658.0, 109025.0, 10976.83586011918, 11014.173936954761, 11048.834267485994, 11097.500612472459, 11207.719807004727, 22116.336509904406, 22136.003879825446, 22176.223832165797, 22205.333839984825, 22222.888885555793, 7654.73208437702, 7658.73194438192, 7683.9615801921755, 7883.054033516015, 7915.722949696733, 4128.731632443882, 4243.554426785143, 4264.658827293871, 4299.398084268205, 4304.074623955865, 3446.638102999199, 3552.964470355315, 3566.465030245432, 3573.4282514797505, 3576.624454432299, 581.7492391358613, 591.9230500035054, 654.7839213059679, 668.903009063683, 669.1434963247052, 525.4463308321555, 601.3925934805877, 625.6496362037274, 633.4552285034866, 644.8839208942435, 7475.14319010195, 9263.16631503168, 9275.536223188814, 9368.531573421438, 9425.0, 537.6290359651828, 649.4544582550585, 649.6589290622461, 655.9278479367266, 659.5218466611764, 4455.603697861534, 4529.796159172779, 4600.838970636079, 4607.239805432133, 4685.461171965199, 107233.0, 113149.0, 152970.0, 162360.0, 162512.74974500443, 135538.3223083898, 137539.0, 138928.0, 139471.0, 141140.0, 107594.84810303907, 120559.0, 120835.79164208421, 120916.0, 121318.78681213075, 8722.089413071686, 8841.0, 8850.468971861452, 8856.64573417056, 9049.457032578066, 59043.704781477536, 64795.67602161959, 66232.27632787901, 66392.33607664055, 67169.0, 79314.20685793068, 97315.51342243527, 98936.0, 99809.0, 101293.0, 10331.483425828828, 11879.287242765462, 11909.22590031645, 11984.820227696766, 12162.939185304016, 11831.981494534855, 12284.570040048555, 12779.87220127824, 12948.15836970594, 13343.332833358487, 11717.531298747954, 11917.702057448632, 12216.816747748613, 12227.510899564273, 12309.815352769894, 11823.82264266054, 11889.464974076014, 11969.922706956415, 12260.38698065111, 14164.362603682654, 13436.93281533586, 13572.660683482993, 13755.862441375459, 13814.102083364549, 14050.718985620435, 11118.833217501582, 11219.046381057702, 11288.661340159808, 11610.709732256424, 11781.0, 9278.536073196448, 11254.887451125383, 11268.042216411697, 11290.15323850698, 11338.489767960627], "value": 7656.695975645049, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 112.0, 141.0, 171.0, 116.0, 101.0, 115.0, 96.0, 172.0, 175.0], "value": 131.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 149.0, 173.0, 97.0, 141.0, 166.0, 143.0, 177.0, 109.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 214.0, 234.0, 210.0, 203.0, 209.0, 237.0, 202.0, 201.0, 192.0], "value": 209.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 83.0, 81.0, 177.0, 62.0, 61.0, 59.0, 68.0, 165.0, 66.0], "value": 88.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 132.0, 69.0, 77.0, 84.0, 114.0, 70.0, 72.0, 113.0], "value": 87.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 92.0, 74.0, 120.0, 130.0, 95.0, 90.0, 125.0, 89.0], "value": 99.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 156.0, 120.0, 97.0, 96.0, 96.0, 96.0, 98.0, 95.0, 98.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 94.0, 92.0, 91.0, 129.0, 151.0, 109.0, 109.0, 149.0, 80.0], "value": 110.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 59.0, 58.0, 58.0, 55.0, 56.0, 54.0, 57.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 77.0, 69.0, 86.0, 78.0, 74.0, 70.0, 74.0, 74.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 86.0, 86.0, 89.0, 88.0, 88.0, 93.0, 92.0, 87.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 174.0, 170.0, 147.0, 141.0, 164.0, 169.0, 140.0, 182.0, 141.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 201.0, 251.0, 198.0, 182.0, 177.0, 178.0, 180.0, 178.0, 178.0], "value": 190.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 68.0, 70.0, 71.0, 69.0, 71.0, 70.0, 80.0, 71.0], "value": 71.3, "unit": "score"}]}]} 11:25:44 INFO - Return code: 0 11:25:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:25:44 ERROR - # TBPL SUCCESS # 11:25:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:25:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:25:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:25:44 INFO - 2016-09-03 11:25:44.447 screenresolution[1965:20393] starting screenresolution argv=screenresolution get 11:25:44 INFO - 2016-09-03 11:25:44.450 screenresolution[1965:20393] Display 0: 1600x1200x32@60 11:25:44 INFO - 2016-09-03 11:25:44.456 screenresolution[1966:20396] starting screenresolution argv=screenresolution list 11:25:44 INFO - Available Modes on Display 0 11:25:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:25:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:25:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:25:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:25:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:25:44 INFO - Intel Iris: 11:25:44 INFO - Chipset Model: Intel Iris 11:25:44 INFO - Type: GPU 11:25:44 INFO - Bus: Built-In 11:25:44 INFO - VRAM (Dynamic, Max): 1536 MB 11:25:44 INFO - Vendor: Intel (0x8086) 11:25:44 INFO - Device ID: 0x0a2e 11:25:44 INFO - Revision ID: 0x0009 11:25:44 INFO - Displays: 11:25:44 INFO - Display: 11:25:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:25:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:25:44 INFO - Display Serial Number: 1600x1200 60 11:25:44 INFO - Main Display: Yes 11:25:44 INFO - Mirror: Off 11:25:44 INFO - Online: Yes 11:25:44 INFO - Rotation: Supported 11:25:44 INFO - Return code: 0 11:25:44 INFO - Running post-action listener: _resource_record_post_action 11:25:44 INFO - [mozharness: 2016-09-03 18:25:44.693158Z] Finished run-tests step (success) 11:25:44 INFO - Running post-run listener: _resource_record_post_run 11:25:44 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 933888; Write bytes: 706494464; Read time: 6; Write time: 2059 11:25:44 INFO - TinderboxPrint: CPU usage
54.2% 11:25:44 INFO - TinderboxPrint: I/O read bytes / time
933,888 / 6 11:25:44 INFO - TinderboxPrint: I/O write bytes / time
706,494,464 / 2,059 11:25:44 INFO - TinderboxPrint: CPU idle
2,219.9 (45.8%) 11:25:44 INFO - TinderboxPrint: CPU system
974.3 (20.1%) 11:25:44 INFO - TinderboxPrint: CPU user
1,647.6 (34.0%) 11:25:44 INFO - TinderboxPrint: Swap in / out
406,319,104 / 0 11:25:44 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387494400; Write bytes: 383059968; Read time: 29299; Write time: 854 11:25:44 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 933888; Write bytes: 297187328; Read time: 6; Write time: 1143 11:25:45 INFO - Running post-run listener: _upload_blobber_files 11:25:45 INFO - Blob upload gear active. 11:25:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:25:45 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 11:25:45 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'] 11:25:45 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 11:25:45 INFO - (blobuploader) - INFO - Open directory for files ... 11:25:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:25:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:25:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:46 INFO - (blobuploader) - INFO - Done attempting. 11:25:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:25:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:25:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:47 INFO - (blobuploader) - INFO - Done attempting. 11:25:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:25:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:25:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:50 INFO - (blobuploader) - INFO - Done attempting. 11:25:50 INFO - (blobuploader) - INFO - Iteration through files over. 11:25:50 INFO - Return code: 0 11:25:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:25:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:25:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35dc4ba4b5d2f272930559ad51d27783f27763099bac6c717635eacf4fe9abd79f6d5301127e4ca4bc05663841c780dda873154d11e909c9809f458f20a113fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81098bb19bd9c9997fd0aaee4826ea434d9ef272964cd550e421bc3f39b0abfcc97e8453931b3e73afdd25c13e75816621b0a3ec3a4efdec95f10b398ac1c147", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b857d958c1def2bab4a37b5ce17c109ef49bcb19d4f1159347cf061d29e40948ab4395db0b14871fb20898bb81825f48fd44ab0d97614a1db7f3baf65252aa73"} 11:25:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:25:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:25:50 INFO - Contents: 11:25:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35dc4ba4b5d2f272930559ad51d27783f27763099bac6c717635eacf4fe9abd79f6d5301127e4ca4bc05663841c780dda873154d11e909c9809f458f20a113fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81098bb19bd9c9997fd0aaee4826ea434d9ef272964cd550e421bc3f39b0abfcc97e8453931b3e73afdd25c13e75816621b0a3ec3a4efdec95f10b398ac1c147", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b857d958c1def2bab4a37b5ce17c109ef49bcb19d4f1159347cf061d29e40948ab4395db0b14871fb20898bb81825f48fd44ab0d97614a1db7f3baf65252aa73"} 11:25:50 INFO - Running post-run listener: copy_logs_to_upload_dir 11:25:50 INFO - Copying logs to upload dir... 11:25:50 INFO - mkdir: /builds/slave/test/build/upload/logs 11:25:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.928988 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-03 11:25:50.428472) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 11:25:50.431929) ========= 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.7pKeEVq8iw/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.0U6NUrrchn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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/35dc4ba4b5d2f272930559ad51d27783f27763099bac6c717635eacf4fe9abd79f6d5301127e4ca4bc05663841c780dda873154d11e909c9809f458f20a113fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81098bb19bd9c9997fd0aaee4826ea434d9ef272964cd550e421bc3f39b0abfcc97e8453931b3e73afdd25c13e75816621b0a3ec3a4efdec95f10b398ac1c147", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b857d958c1def2bab4a37b5ce17c109ef49bcb19d4f1159347cf061d29e40948ab4395db0b14871fb20898bb81825f48fd44ab0d97614a1db7f3baf65252aa73"} build_url:https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011302 build_url: 'https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35dc4ba4b5d2f272930559ad51d27783f27763099bac6c717635eacf4fe9abd79f6d5301127e4ca4bc05663841c780dda873154d11e909c9809f458f20a113fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81098bb19bd9c9997fd0aaee4826ea434d9ef272964cd550e421bc3f39b0abfcc97e8453931b3e73afdd25c13e75816621b0a3ec3a4efdec95f10b398ac1c147", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b857d958c1def2bab4a37b5ce17c109ef49bcb19d4f1159347cf061d29e40948ab4395db0b14871fb20898bb81825f48fd44ab0d97614a1db7f3baf65252aa73"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 11:25:50.465914) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:25:50.466286) ========= 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.7pKeEVq8iw/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.0U6NUrrchn/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.005145 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:25:50.517962) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 11:25:50.518687) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 11:25:50.519377) ========= ========= Total master_lag: 0.18 =========