builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0102 starttime: 1490632604.81 results: success (0) buildid: 20170327075316 builduid: a027e70c3f4840a6910f05d66abdd467 revision: 6f684ac21eaeb4269bd650783deef431b2f114bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:44.809976) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:44.810723) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:44.811222) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:44.839824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:44.840430) ========= 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.DEdimuTPOU/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.8q7YmsYiS4/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-27 09:36:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 132M=0s 2017-03-27 09:36:44 (132 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130289 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:44.996795) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:44.997248) ========= 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.DEdimuTPOU/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.8q7YmsYiS4/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.030408 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:45.050087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-27 09:36:45.050531) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6f684ac21eaeb4269bd650783deef431b2f114bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6f684ac21eaeb4269bd650783deef431b2f114bc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DEdimuTPOU/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.8q7YmsYiS4/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-27 09:36:45,154 truncating revision to first 12 chars 2017-03-27 09:36:45,154 Setting DEBUG logging. 2017-03-27 09:36:45,154 attempt 1/10 2017-03-27 09:36:45,154 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6f684ac21eae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 09:36:46,721 unpacking tar archive at: mozilla-aurora-6f684ac21eae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.833593 ========= master_lag: 1.91 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-03-27 09:36:48.797400) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:48.797750) ========= script_repo_revision: 6f684ac21eaeb4269bd650783deef431b2f114bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:48.798184) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:48.798470) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 09:36:48.819732) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-27 09:36:48.820129) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DEdimuTPOU/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.8q7YmsYiS4/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:36:49 INFO - MultiFileLogger online at 20170327 09:36:49 in /builds/slave/test 09:36:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:36:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:49 INFO - {'append_to_log': False, 09:36:49 INFO - 'base_work_dir': '/builds/slave/test', 09:36:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:36:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:49 INFO - 'branch': 'Mozilla-Aurora', 09:36:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:49 INFO - 'config_files': ('talos/mac_config.py',), 09:36:49 INFO - 'default_actions': ('clobber', 09:36:49 INFO - 'read-buildbot-config', 09:36:49 INFO - 'download-and-extract', 09:36:49 INFO - 'populate-webroot', 09:36:49 INFO - 'create-virtualenv', 09:36:49 INFO - 'install', 09:36:49 INFO - 'run-tests'), 09:36:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:49 INFO - 'download_minidump_stackwalk': True, 09:36:49 INFO - 'download_symbols': 'ondemand', 09:36:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:49 INFO - 'gecko_profile': False, 09:36:49 INFO - 'gecko_profile_interval': 0, 09:36:49 INFO - 'installer_path': 'installer.exe', 09:36:49 INFO - 'log_level': 'info', 09:36:49 INFO - 'log_name': 'talos', 09:36:49 INFO - 'log_to_console': True, 09:36:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:49 INFO - 'opt_config_files': (), 09:36:49 INFO - 'pip_index': False, 09:36:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:49 INFO - 'cmd': ('bash', 09:36:49 INFO - '-c', 09:36:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:49 INFO - 'enabled': True, 09:36:49 INFO - 'halt_on_failure': False, 09:36:49 INFO - 'name': 'check_screen_resolution'},), 09:36:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:49 INFO - 'cmd': ('bash', 09:36:49 INFO - '-c', 09:36:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:49 INFO - 'enabled': True, 09:36:49 INFO - 'halt_on_failure': False, 09:36:49 INFO - 'name': 'check_screen_resolution'},), 09:36:49 INFO - 'run_cmd_checks_enabled': True, 09:36:49 INFO - 'suite': 'dromaeojs', 09:36:49 INFO - 'system_bits': '32', 09:36:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:49 INFO - 'title': 't-yosemite-r7-0102', 09:36:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:49 INFO - 'use_talos_json': True, 09:36:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:49 INFO - 'work_dir': 'build'} 09:36:49 INFO - [mozharness: 2017-03-27 16:36:49.038189Z] Running clobber step. 09:36:49 INFO - Running pre-action listener: _resource_record_pre_action 09:36:49 INFO - Running main action method: clobber 09:36:49 INFO - rmtree: /builds/slave/test/build 09:36:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:50 INFO - Running post-action listener: _resource_record_post_action 09:36:50 INFO - [mozharness: 2017-03-27 16:36:50.585231Z] Finished clobber step (success) 09:36:50 INFO - [mozharness: 2017-03-27 16:36:50.585409Z] Running read-buildbot-config step. 09:36:50 INFO - Running pre-action listener: _resource_record_pre_action 09:36:50 INFO - Running main action method: read_buildbot_config 09:36:50 INFO - Using buildbot properties: 09:36:50 INFO - { 09:36:50 INFO - "project": "", 09:36:50 INFO - "product": "firefox", 09:36:50 INFO - "script_repo_revision": "production", 09:36:50 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:36:50 INFO - "repository": "", 09:36:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:36:50 INFO - "buildid": "20170327075316", 09:36:50 INFO - "pgo_build": "False", 09:36:50 INFO - "basedir": "/builds/slave/test", 09:36:50 INFO - "buildnumber": 36, 09:36:50 INFO - "slavename": "t-yosemite-r7-0102", 09:36:50 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:36:50 INFO - "platform": "yosemite_r7", 09:36:50 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:36:50 INFO - "branch": "mozilla-aurora", 09:36:50 INFO - "repo_path": "releases/mozilla-aurora", 09:36:50 INFO - "revision": "6f684ac21eaeb4269bd650783deef431b2f114bc", 09:36:50 INFO - "stage_platform": "macosx64", 09:36:50 INFO - "builduid": "a027e70c3f4840a6910f05d66abdd467", 09:36:50 INFO - "slavebuilddir": "test" 09:36:50 INFO - } 09:36:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:36:50 INFO - Running post-action listener: _resource_record_post_action 09:36:50 INFO - [mozharness: 2017-03-27 16:36:50.588843Z] Finished read-buildbot-config step (success) 09:36:50 INFO - [mozharness: 2017-03-27 16:36:50.588955Z] Running download-and-extract step. 09:36:50 INFO - Running pre-action listener: _resource_record_pre_action 09:36:50 INFO - Running main action method: download_and_extract 09:36:50 INFO - mkdir: /builds/slave/test/build/tests 09:36:50 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')]} 09:36:50 INFO - trying https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:36:50 INFO - Downloading https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:36:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 09:36:51 INFO - Downloaded 1199 bytes. 09:36:51 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:36:51 INFO - Using the following test package requirements: 09:36:51 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:36:51 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:51 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:36:51 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:51 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:36:51 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:51 INFO - u'jsshell-mac.zip'], 09:36:51 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:51 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:36:51 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:36:51 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:51 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:36:51 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:51 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:36:51 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:51 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:36:51 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:36:51 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:36:51 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:36:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:36:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:36:51 INFO - Fetch https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:36:52 INFO - Expected file size: 51787403 09:36:52 INFO - Obtained file size: 51787403 09:37:09 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:37:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:37:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:37:09 INFO - Fetch https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:37:10 INFO - Expected file size: 51787403 09:37:10 INFO - Obtained file size: 51787403 09:37:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:37:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:37:26 INFO - Fetch https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:37:28 INFO - Expected file size: 14404438 09:37:28 INFO - Obtained file size: 14404438 09:37:28 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')]} 09:37:28 INFO - trying https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:37:28 INFO - Downloading https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:37:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 09:37:30 INFO - Downloaded 74670028 bytes. 09:37:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:37:30 INFO - mkdir: /builds/slave/test/properties 09:37:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:30 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:30 INFO - Contents: 09:37:30 INFO - build_url:https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:37:31 INFO - Running post-action listener: _resource_record_post_action 09:37:31 INFO - Running post-action listener: set_extra_try_arguments 09:37:31 INFO - [mozharness: 2017-03-27 16:37:31.215909Z] Finished download-and-extract step (success) 09:37:31 INFO - [mozharness: 2017-03-27 16:37:31.216059Z] Running populate-webroot step. 09:37:31 INFO - Running pre-action listener: _resource_record_pre_action 09:37:31 INFO - Running main action method: populate_webroot 09:37:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:31 INFO - '--tppagecycles', 09:37:31 INFO - '7'], 09:37:31 INFO - 'tests': ['tsvgm']}}, 09:37:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:37:31 INFO - 'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:37:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:37:31 INFO - 'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['damp', 'tps']}, 09:37:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:37:31 INFO - 'tests': ['damp', 'tps']}, 09:37:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['dromaeo_dom']}, 09:37:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['basic_compositor_video']}, 09:37:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:37:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['a11yr', 09:37:31 INFO - 'ts_paint', 09:37:31 INFO - 'tpaint', 09:37:31 INFO - 'sessionrestore', 09:37:31 INFO - 'sessionrestore_no_auto_restore', 09:37:31 INFO - 'tabpaint']}, 09:37:31 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:31 INFO - 'ts_paint', 09:37:31 INFO - 'tpaint', 09:37:31 INFO - 'sessionrestore', 09:37:31 INFO - 'sessionrestore_no_auto_restore', 09:37:31 INFO - 'tabpaint']}, 09:37:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:37:31 INFO - 'ts_paint', 09:37:31 INFO - 'tpaint', 09:37:31 INFO - 'sessionrestore', 09:37:31 INFO - 'sessionrestore_no_auto_restore', 09:37:31 INFO - 'tabpaint']}, 09:37:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:37:31 INFO - 'ts_paint', 09:37:31 INFO - 'tpaint', 09:37:31 INFO - 'sessionrestore', 09:37:31 INFO - 'sessionrestore_no_auto_restore', 09:37:31 INFO - 'tabpaint']}, 09:37:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['a11yr', 09:37:31 INFO - 'ts_paint', 09:37:31 INFO - 'tpaint', 09:37:31 INFO - 'sessionrestore', 09:37:31 INFO - 'sessionrestore_no_auto_restore', 09:37:31 INFO - 'tabpaint']}, 09:37:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['a11yr', 09:37:31 INFO - 'ts_paint', 09:37:31 INFO - 'tpaint', 09:37:31 INFO - 'sessionrestore', 09:37:31 INFO - 'sessionrestore_no_auto_restore', 09:37:31 INFO - 'tabpaint']}, 09:37:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['tsvgx', 09:37:31 INFO - 'tsvgr_opacity', 09:37:31 INFO - 'tart', 09:37:31 INFO - 'tscrollx', 09:37:31 INFO - 'cart', 09:37:31 INFO - 'tsvg_static']}, 09:37:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:31 INFO - 'tsvgr_opacity', 09:37:31 INFO - 'tart', 09:37:31 INFO - 'tscrollx', 09:37:31 INFO - 'cart', 09:37:31 INFO - 'tsvg_static']}, 09:37:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:37:31 INFO - 'talos_options': ['--disable-e10s'], 09:37:31 INFO - 'tests': ['tp5o']}, 09:37:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:37:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:37:31 INFO - 'talos_options': ['--disable-e10s', 09:37:31 INFO - '--xperf_path', 09:37:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:31 INFO - 'tests': ['tp5n']}, 09:37:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:37:31 INFO - 'talos_options': ['--xperf_path', 09:37:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:31 INFO - 'tests': ['tp5n']}}, 09:37:31 INFO - 'talos.zip': {'path': '', 09:37:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:31 INFO - Running post-action listener: _resource_record_post_action 09:37:31 INFO - [mozharness: 2017-03-27 16:37:31.234897Z] Finished populate-webroot step (success) 09:37:31 INFO - [mozharness: 2017-03-27 16:37:31.235029Z] Running create-virtualenv step. 09:37:31 INFO - Running pre-action listener: _resource_record_pre_action 09:37:31 INFO - Running main action method: create_virtualenv 09:37:31 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:31 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 09:37:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:31 INFO - Using real prefix '/tools/python27' 09:37:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:32 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:34 INFO - Installing pip.................done. 09:37:34 INFO - Return code: 0 09:37:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:37:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:37:35 INFO - Reading from file tmpfile_stdout 09:37:35 INFO - Output received: 09:37:35 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:37:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37:35 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')]} 09:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:35 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')]} 09:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:35 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:37:35 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 09:37:35 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 09:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:35 INFO - 'HOME': '/Users/cltbld', 09:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:35 INFO - 'LOGNAME': 'cltbld', 09:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:35 INFO - 'MOZ_NO_REMOTE': '1', 09:37:35 INFO - 'NO_EM_RESTART': '1', 09:37:35 INFO - 'PAGER': '/bin/cat', 09:37:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:35 INFO - 'PWD': '/builds/slave/test', 09:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:35 INFO - 'SHELL': '/bin/bash', 09:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:37:35 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:35 INFO - 'USER': 'cltbld', 09:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:35 INFO - 'XPC_FLAGS': '0x0', 09:37:35 INFO - 'XPC_SERVICE_NAME': '0', 09:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:35 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 09:37:35 INFO - Cleaning up... 09:37:35 INFO - Return code: 0 09:37:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37:35 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')]} 09:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:35 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')]} 09:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:35 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:37:35 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 09:37:35 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 09:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:35 INFO - 'HOME': '/Users/cltbld', 09:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:35 INFO - 'LOGNAME': 'cltbld', 09:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:35 INFO - 'MOZ_NO_REMOTE': '1', 09:37:35 INFO - 'NO_EM_RESTART': '1', 09:37:35 INFO - 'PAGER': '/bin/cat', 09:37:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:35 INFO - 'PWD': '/builds/slave/test', 09:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:35 INFO - 'SHELL': '/bin/bash', 09:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:37:35 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:35 INFO - 'USER': 'cltbld', 09:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:35 INFO - 'XPC_FLAGS': '0x0', 09:37:35 INFO - 'XPC_SERVICE_NAME': '0', 09:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:35 INFO - Downloading/unpacking psutil>=3.1.1 09:37:35 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 09:37:35 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 09:37:35 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 09:37:35 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 09:37:35 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 09:37:35 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 09:37:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:39 INFO - Installing collected packages: psutil 09:37:39 INFO - Running setup.py install for psutil 09:37:39 INFO - building 'psutil._psutil_osx' extension 09:37:39 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 09:37:40 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 09:37:40 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 09:37:40 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 09:37:40 INFO - building 'psutil._psutil_posix' extension 09:37:40 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 09:37:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:37:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:37:40 INFO - ^ 09:37:40 INFO - 1 warning generated. 09:37:40 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 09:37:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:40 INFO - Successfully installed psutil 09:37:40 INFO - Cleaning up... 09:37:40 INFO - Return code: 0 09:37:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37: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')]} 09:37:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:40 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:37:40 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 09:37:40 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 09:37:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:37:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:40 INFO - 'HOME': '/Users/cltbld', 09:37:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:40 INFO - 'LOGNAME': 'cltbld', 09:37:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:40 INFO - 'MOZ_NO_REMOTE': '1', 09:37:40 INFO - 'NO_EM_RESTART': '1', 09:37:40 INFO - 'PAGER': '/bin/cat', 09:37:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:40 INFO - 'PWD': '/builds/slave/test', 09:37:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:40 INFO - 'SHELL': '/bin/bash', 09:37:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:37:40 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:37:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:40 INFO - 'USER': 'cltbld', 09:37:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:40 INFO - 'XPC_FLAGS': '0x0', 09:37:40 INFO - 'XPC_SERVICE_NAME': '0', 09:37:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:40 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 09:37:40 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 09:37:40 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 09:37:40 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 09:37:40 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 09:37:40 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 09:37:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:44 INFO - Installing collected packages: mozsystemmonitor 09:37:44 INFO - Running setup.py install for mozsystemmonitor 09:37:44 INFO - Successfully installed mozsystemmonitor 09:37:44 INFO - Cleaning up... 09:37:44 INFO - Return code: 0 09:37:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37:44 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')]} 09:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:44 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')]} 09:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:44 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:37:44 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 09:37:44 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 09:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:44 INFO - 'HOME': '/Users/cltbld', 09:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:44 INFO - 'LOGNAME': 'cltbld', 09:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:44 INFO - 'MOZ_NO_REMOTE': '1', 09:37:44 INFO - 'NO_EM_RESTART': '1', 09:37:44 INFO - 'PAGER': '/bin/cat', 09:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:44 INFO - 'PWD': '/builds/slave/test', 09:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:44 INFO - 'SHELL': '/bin/bash', 09:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:37:44 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:44 INFO - 'USER': 'cltbld', 09:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:44 INFO - 'XPC_FLAGS': '0x0', 09:37:44 INFO - 'XPC_SERVICE_NAME': '0', 09:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:44 INFO - Downloading/unpacking jsonschema==2.5.1 09:37:44 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 09:37:44 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 09:37:44 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 09:37:44 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 09:37:44 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 09:37:44 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 09:37:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37:47 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 09:37:47 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 09:37:47 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 09:37:47 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 09:37:47 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 09:37:47 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 09:37:48 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:48 INFO - warning: no files found matching '*.txt' 09:37:48 INFO - no previously-included directories found matching 'build' 09:37:48 INFO - no previously-included directories found matching 'dist' 09:37:48 INFO - no previously-included directories found matching '.git*' 09:37:48 INFO - Installing collected packages: jsonschema, functools32 09:37:48 INFO - Running setup.py install for functools32 09:37:48 INFO - warning: no files found matching '*.txt' 09:37:48 INFO - no previously-included directories found matching 'build' 09:37:48 INFO - no previously-included directories found matching 'dist' 09:37:48 INFO - no previously-included directories found matching '.git*' 09:37:48 INFO - Successfully installed jsonschema functools32 09:37:48 INFO - Cleaning up... 09:37:48 INFO - Return code: 0 09:37:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:48 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')]} 09:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:48 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')]} 09:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:48 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:37:48 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 09:37:48 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 09:37:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:37:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:48 INFO - 'HOME': '/Users/cltbld', 09:37:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:48 INFO - 'LOGNAME': 'cltbld', 09:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:48 INFO - 'MOZ_NO_REMOTE': '1', 09:37:48 INFO - 'NO_EM_RESTART': '1', 09:37:48 INFO - 'PAGER': '/bin/cat', 09:37:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:48 INFO - 'PWD': '/builds/slave/test', 09:37:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:48 INFO - 'SHELL': '/bin/bash', 09:37:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:37:48 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:37:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:48 INFO - 'USER': 'cltbld', 09:37:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:48 INFO - 'XPC_FLAGS': '0x0', 09:37:48 INFO - 'XPC_SERVICE_NAME': '0', 09:37:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:48 INFO - Cleaning up... 09:37:48 INFO - Return code: 0 09:37:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:48 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')]} 09:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:48 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')]} 09:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:48 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:37:48 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 09:37:48 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 09:37:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:37:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:48 INFO - 'HOME': '/Users/cltbld', 09:37:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:48 INFO - 'LOGNAME': 'cltbld', 09:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:48 INFO - 'MOZ_NO_REMOTE': '1', 09:37:48 INFO - 'NO_EM_RESTART': '1', 09:37:48 INFO - 'PAGER': '/bin/cat', 09:37:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:48 INFO - 'PWD': '/builds/slave/test', 09:37:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:48 INFO - 'SHELL': '/bin/bash', 09:37:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:37:48 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:37:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:48 INFO - 'USER': 'cltbld', 09:37:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:48 INFO - 'XPC_FLAGS': '0x0', 09:37:48 INFO - 'XPC_SERVICE_NAME': '0', 09:37:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:48 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:48 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 09:37:48 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 09:37:48 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 09:37:48 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 09:37:48 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 09:37:48 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 09:37:51 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:51 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 09:37:51 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 09:37:51 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 09:37:51 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 09:37:51 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 09:37:51 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 09:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37:52 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 09:37:52 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 09:37:52 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 09:37:52 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 09:37:52 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 09:37:52 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 09:37:52 INFO - Downloading docopt-0.6.1.tar.gz 09:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:52 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:52 INFO - Running setup.py install for blobuploader 09:37:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:53 INFO - Running setup.py install for requests 09:37:53 INFO - Running setup.py install for docopt 09:37:53 INFO - Successfully installed blobuploader requests docopt 09:37:53 INFO - Cleaning up... 09:37:53 INFO - Return code: 0 09:37:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:53 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')]} 09:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:53 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')]} 09:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:53 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:37:53 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 09:37:53 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 09:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:53 INFO - 'HOME': '/Users/cltbld', 09:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:53 INFO - 'LOGNAME': 'cltbld', 09:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:53 INFO - 'MOZ_NO_REMOTE': '1', 09:37:53 INFO - 'NO_EM_RESTART': '1', 09:37:53 INFO - 'PAGER': '/bin/cat', 09:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:53 INFO - 'PWD': '/builds/slave/test', 09:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:53 INFO - 'SHELL': '/bin/bash', 09:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:37:53 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:53 INFO - 'USER': 'cltbld', 09:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:53 INFO - 'XPC_FLAGS': '0x0', 09:37:53 INFO - 'XPC_SERVICE_NAME': '0', 09:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-1Saz8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-TyXC2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-uWgdVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-paEqB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-45sfVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-h1yBj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-scxHwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-J8cJrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-VJL8jA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-0AUJMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:55 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-TIDysm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:55 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-7tbLpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:55 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-k8WXhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:55 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-029RkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:55 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-8tEdy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:55 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-1XnLxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:55 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-WkeLLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:55 INFO - Running setup.py install for manifestparser 09:37:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:55 INFO - Running setup.py install for mozcrash 09:37:56 INFO - Running setup.py install for mozdebug 09:37:56 INFO - Running setup.py install for mozdevice 09:37:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:37:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:56 INFO - Running setup.py install for mozfile 09:37:56 INFO - Running setup.py install for mozhttpd 09:37:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:56 INFO - Running setup.py install for mozinfo 09:37:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:56 INFO - Running setup.py install for mozInstall 09:37:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:57 INFO - Running setup.py install for mozleak 09:37:57 INFO - Running setup.py install for mozlog 09:37:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:57 INFO - Running setup.py install for moznetwork 09:37:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:57 INFO - Running setup.py install for mozprocess 09:37:57 INFO - Running setup.py install for mozprofile 09:37:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:57 INFO - Running setup.py install for mozrunner 09:37:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:58 INFO - Running setup.py install for mozscreenshot 09:37:58 INFO - Running setup.py install for moztest 09:37:58 INFO - Running setup.py install for mozversion 09:37:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:58 INFO - Cleaning up... 09:37:58 INFO - Return code: 0 09:37:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:58 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')]} 09:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 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')]} 09:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:37:58 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 09:37:58 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 09:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:58 INFO - 'HOME': '/Users/cltbld', 09:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:58 INFO - 'LOGNAME': 'cltbld', 09:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:58 INFO - 'MOZ_NO_REMOTE': '1', 09:37:58 INFO - 'NO_EM_RESTART': '1', 09:37:58 INFO - 'PAGER': '/bin/cat', 09:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:58 INFO - 'PWD': '/builds/slave/test', 09:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:58 INFO - 'SHELL': '/bin/bash', 09:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:37:58 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:58 INFO - 'USER': 'cltbld', 09:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:58 INFO - 'XPC_FLAGS': '0x0', 09:37:58 INFO - 'XPC_SERVICE_NAME': '0', 09:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-hR7d9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:58 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)) 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-OkSNG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-m0Y0ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:59 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)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-iMtEDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-hvS9lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:59 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)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-UAEyGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:59 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)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-R5eL0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:59 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)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-azyYG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:59 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)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-gvz5ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:59 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)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-kxGAbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-f_B2Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:59 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)) 09:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-t6MvMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:00 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-JNqYaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:00 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)) 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:00 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-nd1m86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:00 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-_185aU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:00 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)) 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:00 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-V_OUsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:00 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-fBqPSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:00 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)) 09:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:00 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)) 09:38:00 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:00 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:00 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 09:38:00 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 09:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:03 INFO - Downloading blessings-1.6.tar.gz 09:38:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:03 INFO - Installing collected packages: blessings 09:38:03 INFO - Running setup.py install for blessings 09:38:03 INFO - Successfully installed blessings 09:38:03 INFO - Cleaning up... 09:38:03 INFO - Return code: 0 09:38:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:03 INFO - Reading from file tmpfile_stdout 09:38:03 INFO - Current package versions: 09:38:03 INFO - blessings == 1.6 09:38:03 INFO - blobuploader == 1.2.4 09:38:03 INFO - docopt == 0.6.1 09:38:03 INFO - functools32 == 3.2.3-2 09:38:03 INFO - jsonschema == 2.5.1 09:38:03 INFO - manifestparser == 1.1 09:38:03 INFO - mozInstall == 1.12 09:38:03 INFO - mozcrash == 1.0 09:38:03 INFO - mozdebug == 0.1 09:38:03 INFO - mozdevice == 0.49 09:38:03 INFO - mozfile == 1.2 09:38:03 INFO - mozhttpd == 0.7 09:38:03 INFO - mozinfo == 0.9 09:38:03 INFO - mozleak == 0.1 09:38:03 INFO - mozlog == 3.4 09:38:03 INFO - moznetwork == 0.27 09:38:03 INFO - mozprocess == 0.25 09:38:03 INFO - mozprofile == 0.28 09:38:03 INFO - mozrunner == 6.13 09:38:03 INFO - mozscreenshot == 0.1 09:38:03 INFO - mozsystemmonitor == 0.3 09:38:03 INFO - moztest == 0.8 09:38:03 INFO - mozversion == 1.4 09:38:03 INFO - psutil == 3.1.1 09:38:03 INFO - requests == 1.2.3 09:38:03 INFO - wsgiref == 0.1.2 09:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:03 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')]} 09:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:03 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')]} 09:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:03 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:38:03 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 09:38:03 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 09:38:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:38:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:03 INFO - 'HOME': '/Users/cltbld', 09:38:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:03 INFO - 'LOGNAME': 'cltbld', 09:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:03 INFO - 'MOZ_NO_REMOTE': '1', 09:38:03 INFO - 'NO_EM_RESTART': '1', 09:38:03 INFO - 'PAGER': '/bin/cat', 09:38:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:03 INFO - 'PWD': '/builds/slave/test', 09:38:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:03 INFO - 'SHELL': '/bin/bash', 09:38:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:38:03 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:38:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:03 INFO - 'USER': 'cltbld', 09:38:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:03 INFO - 'XPC_FLAGS': '0x0', 09:38:03 INFO - 'XPC_SERVICE_NAME': '0', 09:38:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 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)) 09:38:04 INFO - Cleaning up... 09:38:04 INFO - Return code: 0 09:38:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:04 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')]} 09:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:04 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')]} 09:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:04 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 0x109200b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109203768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad29554d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, '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.DEdimuTPOU/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.8q7YmsYiS4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 09:38:04 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 09:38:04 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 09:38:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:38:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:04 INFO - 'HOME': '/Users/cltbld', 09:38:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:04 INFO - 'LOGNAME': 'cltbld', 09:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:04 INFO - 'MOZ_NO_REMOTE': '1', 09:38:04 INFO - 'NO_EM_RESTART': '1', 09:38:04 INFO - 'PAGER': '/bin/cat', 09:38:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:04 INFO - 'PWD': '/builds/slave/test', 09:38:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:04 INFO - 'SHELL': '/bin/bash', 09:38:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:38:04 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:38:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:04 INFO - 'USER': 'cltbld', 09:38:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:04 INFO - 'XPC_FLAGS': '0x0', 09:38:04 INFO - 'XPC_SERVICE_NAME': '0', 09:38:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:04 INFO - Cleaning up... 09:38:04 INFO - Return code: 0 09:38:04 INFO - Running post-action listener: _resource_record_post_action 09:38:04 INFO - Running post-action listener: _start_resource_monitoring 09:38:04 INFO - Starting resource monitoring. 09:38:04 INFO - [mozharness: 2017-03-27 16:38:04.398786Z] Finished create-virtualenv step (success) 09:38:04 INFO - [mozharness: 2017-03-27 16:38:04.399827Z] Running install step. 09:38:04 INFO - Running pre-action listener: _resource_record_pre_action 09:38:04 INFO - Running main action method: install 09:38:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:04 INFO - Reading from file tmpfile_stdout 09:38:04 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:04 INFO - Reading from file tmpfile_stdout 09:38:04 INFO - Output received: 09:38:04 INFO - Usage: mozinstall [options] installer 09:38:04 INFO - Options: 09:38:04 INFO - -h, --help show this help message and exit 09:38:04 INFO - -d DEST, --destination=DEST 09:38:04 INFO - Directory to install application into. [default: 09:38:04 INFO - "/builds/slave/test"] 09:38:04 INFO - --app=APP Application being installed. [default: firefox] 09:38:04 INFO - mkdir: /builds/slave/test/build/application 09:38:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:38:24 INFO - Reading from file tmpfile_stdout 09:38:24 INFO - Output received: 09:38:24 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:38:24 INFO - Running post-action listener: _resource_record_post_action 09:38:24 INFO - [mozharness: 2017-03-27 16:38:24.826177Z] Finished install step (success) 09:38:24 INFO - [mozharness: 2017-03-27 16:38:24.826322Z] Running run-tests step. 09:38:24 INFO - Running pre-action listener: _resource_record_pre_action 09:38:24 INFO - Running main action method: run_tests 09:38:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:24 INFO - 2017-03-27 09:38:24.841 screenresolution[1630:7493] starting screenresolution argv=screenresolution get 09:38:24 INFO - 2017-03-27 09:38:24.843 screenresolution[1630:7493] Display 0: 1600x1200x32@60 09:38:24 INFO - 2017-03-27 09:38:24.849 screenresolution[1631:7495] starting screenresolution argv=screenresolution list 09:38:24 INFO - Available Modes on Display 0 09:38:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:25 INFO - Intel Iris: 09:38:25 INFO - Chipset Model: Intel Iris 09:38:25 INFO - Type: GPU 09:38:25 INFO - Bus: Built-In 09:38:25 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:25 INFO - Vendor: Intel (0x8086) 09:38:25 INFO - Device ID: 0x0a2e 09:38:25 INFO - Revision ID: 0x0009 09:38:25 INFO - Displays: 09:38:25 INFO - Display: 09:38:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:25 INFO - Display Serial Number: 1600x1200 60 09:38:25 INFO - Main Display: Yes 09:38:25 INFO - Mirror: Off 09:38:25 INFO - Online: Yes 09:38:25 INFO - Rotation: Supported 09:38:25 INFO - Return code: 0 09:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:25 INFO - Python 2.7.3 09:38:25 INFO - Return code: 0 09:38:25 INFO - grabbing minidump binary from tooltool 09:38:25 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')]} 09:38:25 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 0x108fded30>, '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 0x108f05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fd3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:25 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 09:38:25 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 09:38:25 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 09:38:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:25 INFO - Return code: 0 09:38:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:38:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:25 INFO - ENV: RUST_BACKTRACE is now 1 09:38:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:38:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0102 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DEdimuTPOU/Render', 09:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:25 INFO - 'HOME': '/Users/cltbld', 09:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:25 INFO - 'LOGNAME': 'cltbld', 09:38:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:25 INFO - 'MOZ_NO_REMOTE': '1', 09:38:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:25 INFO - 'NO_EM_RESTART': '1', 09:38:25 INFO - 'PAGER': '/bin/cat', 09:38:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:25 INFO - 'PWD': '/builds/slave/test', 09:38:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:25 INFO - 'RUST_BACKTRACE': '1', 09:38:25 INFO - 'SHELL': '/bin/bash', 09:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8q7YmsYiS4/Listeners', 09:38:25 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 09:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:25 INFO - 'USER': 'cltbld', 09:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:25 INFO - 'XPC_FLAGS': '0x0', 09:38:25 INFO - 'XPC_SERVICE_NAME': '0', 09:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:38:25 INFO - mozversion application_buildid: 20170327075316 09:38:25 INFO - mozversion application_changeset: 6f684ac21eaeb4269bd650783deef431b2f114bc 09:38:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:38:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:25 INFO - mozversion application_name: Firefox 09:38:25 INFO - mozversion application_remotingname: firefox-dev 09:38:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:38:25 INFO - mozversion application_vendor: Mozilla 09:38:25 INFO - mozversion application_version: 54.0a2 09:38:25 INFO - mozversion platform_buildid: 20170327075316 09:38:25 INFO - mozversion platform_changeset: 6f684ac21eaeb4269bd650783deef431b2f114bc 09:38:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:38:25 INFO - mozversion platform_version: 54.0a2 09:38:25 INFO - using testdate: 1490632705 09:38:25 INFO - actual date: 1490632705 09:38:25 INFO - starting webserver on 'localhost:49248' 09:38:25 INFO - SUITE-START | Running 2 tests 09:38:25 INFO - TEST-START | dromaeo_css 09:38:25 INFO - operating with platform_type : mac_ 09:38:25 INFO - Initialising browser for dromaeo_css test... 09:38:25 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpyt7q3A/profile http://localhost:49248/getInfo.html) 09:38:27 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 09:38:27 INFO - PROCESS | 1641 | colorDepth:24 09:38:27 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 09:38:27 INFO - PROCESS | 1641 | __metrics 09:38:27 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:38:27 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:38:28 INFO - TEST-INFO | 1641: exit 0 09:38:28 INFO - Browser initialized. 09:38:28 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:28 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpyt7q3A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:40 INFO - PROCESS | 1643 | RSS: Main: 215756800 09:38:40 INFO - PROCESS | 1643 | 09:41:45 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 09:41:47 INFO - PROCESS | 1643 | RSS: Main: 398290944 09:41:47 INFO - PROCESS | 1643 | 09:43:30 INFO - PROCESS | 1643 | ************************* 09:43:30 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 09:43:30 INFO - PROCESS | 1643 | 09:43:30 ERROR - PROCESS | 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:43:30 INFO - PROCESS | 1643 | Full stack: 09:43:30 INFO - PROCESS | 1643 | ************************* 09:44:50 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 09:44:51 INFO - PROCESS | 1643 | RSS: Main: 347533312 09:44:51 INFO - PROCESS | 1643 | 09:47:53 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 09:47:54 INFO - PROCESS | 1643 | RSS: Main: 315424768 09:47:54 INFO - PROCESS | 1643 | 09:50:56 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 09:50:57 INFO - PROCESS | 1643 | RSS: Main: 319766528 09:50:57 INFO - PROCESS | 1643 | 09:54:00 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 09:54:01 INFO - PROCESS | 1643 | RSS: Main: 326295552 09:54:01 INFO - PROCESS | 1643 | 09:57:04 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 09:57:04 INFO - PROCESS | 1643 | RSS: Main: 544870400 09:57:04 INFO - PROCESS | 1643 | 09:57:04 INFO - PROCESS | 1643 | __start_tp_report 09:57:04 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 09:57:04 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 09:57:04 INFO - PROCESS | 1643 | |i|pagename|runs| 09:57:04 INFO - PROCESS | 1643 | |0;dojo.html;2581.9801178534485;3268.2646404558964;3430.828458577071;3438.487065691097;3457.4813777933323;5979.671118088503;6010.6093103948215;6031.879362412749;6051.939480605198;6084.421979911913;5962.10568414738;6003.579749417542;6006.7597296108215;6016.127661489092;6026.397360263972;4362.454693163355;5292.259083728281;5340.91988620171;5376.435474275184;5477.397486276506;5254.185601231796;5263.473652634744;5318.627696061278;5350.839474815762;5460.099083651193;5344.144936810111;5359;5371.973140134313;5376.596755243353;5383.353997520303;5331.973340133274;5332.306800115999;5346.4118946915805;5347.983883062206;5370.624056316079;5206.479352064801;5309.5752339812725;5311.309529761106;5318.468153184689;5351.25082488452;5942.197803296543;5955.34491205967;5969.5821292509545;5975.1037344398255;5977.312609049972;6318.083877837687;6319.4944404448;6333.24001119864;6341.429271365553;6344.3655634436645;6292.937070629327;6317.273513545909;6364.586301890362;6411.8397040073905;6411.96794016027;6057.727402266909;6151.661658608775;6161.229846269216;6171.413715697002;6207.534434917354;6262.6868656566985;6278.30938596754;6614.2393624733295;6628.403443689995;6634.469242460592;6233;6239.844003899939;6273.937260627426;6296.748130074838;6335.524835637253;6450.903236451457;6459.870802583922;6465.288818229991;6468.126802881645;6471.6764161791725;6359.713812878432;6363.840903977438;6365.618062916147;6377.330380310001;6379.330170332141;5228.4510126436935;6142.406692085091;6217.191765070603;6287.088372186007;6288.591241569284;5106.02985432774;5231.450697676765;5243.370795504564;5255.02781985334;5257.710825904535;4630.934884976475;4659.5573420525;4659.860204193879;4673.556012178838;4702.153612349742;5627.071533197059;5767.509761670221;5778.306603207558;5788.90010897928;6062.242219722535;6259.123722678829;6276.717547710364;6298;6315.021171718391;6316.115743795872;6108.175396321529;6123.4351415035735;6125.081237814275;6132.846678832976;6137.41694539018;5715.657060576379;5719.19931209631;5727.140928860621;5730.484256416942;5750.683712395775;6660.733570657313;6709.329067093252;6709.993500974795;6726.966365168143;6731.46148308144;6256.812295631138;6274.090256912813;6292.18201633803;6316.431521163117;6320.241571011508;6408.263049749152;6408.359164083741;6507.804765857032;6535.869282614416;6612.371824676648;6342.841428964312;6353.237611486651;6363.3636636336;6364.109024736633;6380.712867920862;5812.779316343446;6416.13382193398;6435.292117867125;6439.7424103035355;6455.0640157176;5513.393526711979;6160.507159427146;6176.413240742122;6203.875922481435;6294.716737746904;6268.623882566878;6274.309825919054;6280.591761535486;6282.685865706788;6300.2754683212015;6203.689815509297;6280.717367718372;6298.685065746786;6305;6307.653079080511;6111.40942266768;6164.845878853064;6198.442140207403;6215.627062376272;6293.307736149111;5976;6282.277538083179;6299.149614802126;6302.716377763103;6326.3041065483685 09:57:04 INFO - PROCESS | 1643 | |1;ext.html;744.4044764188651;792.3542313014892;813.6094674556215;868.2099289646424;881.3742243007464;27222;39202.607973920254;39720.602793972124;39745.602543974586;39850.40224396636;40417.393739093866;40518.98702532438;40572.59427405725;40641.187176256455;40677.59322406775;7324.48728588999;7676.5394076355315;9191.724248272563;9195.586198621044;9200.079992000814;7300.780976570712;10830.40432776197;11059.50232239547;11161.776764464707;11180.720481987933;10972.725681857977;10975.012248897598;11006.073937061023;11013.394263315515;11060.889391106106;10971.780564388708;10987.450627468674;11007.779844403147;11010.119190464784;11031.944840275748;7176.564687062556;7354.374878135365;7387.741429050011;7401.481896267263;7410.221926697727;38155.23689526222;38470.80764596187;38545.22909541793;38580.421293680614;38694.22611547782;3832.0419895026243;3833.310004199243;3857.4406711026745;3876.8255428505786;3890.3580909149955;26455.603165952525;26458;26822.865885670642;27870.5819412709;27912.720872791215;2815.9296017599518;2821.84479853608;2843.1470558832475;3020.7432368248724;3027.273454370957;367000;367120;367359;368580.1570992155;369124;51507.48492515102;51668.22497662463;51714.37999340086;51718;51745.22382164271;53275.20087198695;53534.464655352946;53589.46410535923;53624.73187634037;53643.731781340844;14660.340284687214;14785.630359241106;14806.777898331533;14809.407623694931;14820.25898705082;10874.945625271823;12705.872941270654;12706.30115343665;12711.046671499675;12717.809232861515;10098.495075246208;10158.98410158993;13729.313534323244;13970.790438143436;14009.299535023412;5710.942890571124;5979.730912108966;5982.521398288127;6020.217371741645;6028.156058151862;827;959.5633986536109;975.1029053271008;977.2719324103589;996.8255555277798;28606;29368.412631747673;29730.702692973227;29982.55026174609;30011.849940750155;1034.5448002878709;1041.8645576075214;1061.2200032975757;1071.5392381276126;1073.5437439088357;13794.862051379558;13815.516456923959;14854;15230.543083707507;15279.694406111816;503278;504585;505507;507159;508533;56014;56358.99999999918;56466.71766641141;56555.43444565502;56590.7170464145;1412.7527682655493;1447.1823419767798;1454.8254209494928;1461.5250043735616;1463.5536161470854;26694.466110678066;26932.865335673196;28285.43429131447;28316.716832831407;28978.565321520615;45126.32310515279;45588.54411455976;45597.088058239315;45690.77154614206;45716;467381;468047;468230;468398;469338;32854.34291314208;34506.65493345135;34954.951351459495;35235.82382088073;35344.64655353517;30252.54621180728;40686.1862762749;42674.146517070105;44186.33720494131;45391.773041134584;29744.702552974195;30097.84951075318;30112;30521.542176866922;30750;24220.75779242185;29500.967466138583;29780.106596802132;30325.090247292617;30361.69638303677;32280.192995175308;32685.019449416555;34391;34499.4825077619;36230.27539449144;27373.494457805013;28219.012334568193;28719.42561148807;29055.983040593477;29113.708862911946;22598.209062682727;22643.320700379016;23079.307620771404;23167.53664926726;23174.768252317936 09:57:04 INFO - PROCESS | 1643 | |2;jquery.html;407.49063670411965;413.7765606572451;419.5992826850358;429.35382249714183;446.3862189489452;18580.349687760914;19766.30817921373;20977.16091356348;20995.47511312218;21076;14505;21958;22294.442638934033;22577.096916123377;22577.887110564465;6317.715702793374;6642.5350225484235;6650.70071846768;6654.900176497356;6664.233613134503;6260.217472815898;6502.6098434093865;6557.70490327934;6563.54055216135;6581;6321.620702757827;6527.151470308853;6555.80332590023;6566.63883486408;6567.54027218095;6494.837629059288;6565.1137096492075;6607.273199948026;6619.238787539446;6620.867582648369;6478.708458119396;6488.675566221717;6494.220693516808;6615.338466153395;6894.896576551306;25204.74795252042;25290.620640690406;25416.745832541623;25594.74405255942;25704.74295257061;2458.483718419134;2833.2916770807296;2862.298736809474;2917.416516696669;2927.2096533935933;15269.694606107927;15363.308651110727;15389.230538473032;16084.59788505285;16509.58726031847;1575.6691094869977;1999.7000449932482;2012.3258708332726;2038.3069756282787;2045.4170561389933;148167;164821;171755;177780;178295.10852445656;20072.19711211535;20192.69710954337;20263.290784822755;20303.39089827278;20308.69536956947;31633.734650614177;32236.677633223837;32284.35431291361;32337.35325293481;32344.191395215308;8640.654373824977;9029.23251523628;9155.81688366229;9165.266778657691;10056.69829905104;6784.932150678529;7600.9619951900995;7605.163432022473;7620.276073772982;7623.047119110111;6554.345490076526;8277.875831862528;9193.172614464731;9196.218321442619;9209.539523023823;3838.5009948707047;3917.5690674025836;3940.8620698275995;3952.4268980998168;3958.8614398495915;880.8986966498807;881.9647214111492;883.5847151838625;921.6129225725211;924.2929159193222;20244.493887652632;21141.365759027252;21478;21636.78363216379;21659.458513537287;903.6249956268075;909.2089881802742;928.6471140966389;933.2254228989922;934.8878134623752;8601.870971935426;10667.306625069346;11074.889251107386;11209.43952802373;11213.327200368005;694019;728319;764176;766932.3306766972;775840;133947;139978.60021399654;161783;162814;166679;1156.259993604094;1174.8355230267769;1184.922980006297;1197.4731118307743;1199.9580014699443;17713.380031699344;18684.25262989465;18689.626207476045;18711.625767484846;18723.378726978506;58808.11787823272;62963.6851815738;63252;63508;64057.35942640514;72218;91643.54178229066;94059.52970235379;94475.86165843184;94782;10891.237613366988;11319.151063670093;11427.714307142389;11429.828552571884;11825.704357391134;12416.627501174979;12576.685582860502;12679.23924564529;12743.941454051146;12799.936000319938;10487.16102711766;11048.723781905517;11160.888391115985;11218.382988935688;11250.212485125963;10142.695719128438;10267.281290309607;10288.588456461957;10446.112080473245;10533.157347412036;11081.229692576855;11171.050460710605;11404.315741055232;11405.144614153805;11444.656660300205;11367.976882080653;11510.712232193928;11539.307641541534;11646.359450230319;11780.234284771463;10471.424071675827;10672.146228301563;10917.01532431396;11132.276402033842;11177.944110279397 09:57:04 INFO - PROCESS | 1643 | |3;mootools.html;1535.6621543260478;1550.9922450387746;1563.1871426858033;1593.6175317923694;1597.217363491889;10446.320989135711;10985.231033827635;10988.066014388787;10997.230193886431;11007;10525.684229473109;10949.781004379907;11014.559417623286;11024.614138505154;11025.173112016611;2367.7395486496516;2400.423898264413;2405.386626410263;2406.362313986786;2410.276916924924;2301.045066297493;2378.0725517048363;2379.393254720044;2384.523095380931;2397.220903206456;2305.2392710405525;2368.834181607288;2371.991903441039;2389.880505974694;2394.580948334043;2308.319045881469;2364.4443555764383;2365.065799009382;2369.620860662286;2374.204641445118;2353.435175557871;2375.5486457573006;2378.452955820154;2381.7975472084895;2382.3567636737985;10481;10698.62554810578;10704;10704.839427408595;10728.517216725266;5694.402087780765;5868.823935281948;5870.589058765888;5873.324567674729;5887.205227294303;3454.63726308739;3491.423915054013;3562.447820587813;3596.32074104791;3655.305491956546;1139.4587570903755;1158.1834806461509;1198.8441502604692;1202.326697049655;1205.6443349211916;13521.188728676212;13864.861351386558;14099.78850317246;15066.849331506653;15285.235738213156;4568.657350698711;4713.34013238147;4742.4309082909585;4743.288506724019;4745.976270118627;10128.797424051625;10382.948085259526;11541.884581154249;11565.074794016458;11582.594609188638;5477.589180811455;5629.43705629442;5657.377688454267;5663.348714897757;5673.517750991129;5153.561947234527;5180.4560521145495;5205.9739701302005;5226.817061402832;5231.659942103751;3796.240751849641;3803.8668646597234;3839.9232015359535;3876.96123038766;3890.513685789276;1021.1473419694489;1021.1932573267036;1021.7905329407603;1035.440861934562;1040.3185913226698;908.8591268353416;920.7790130368663;934.8223837470995;938.7465384346189;979.2459805949379;6415.871682566416;6429.32492088324;6572.539922205496;6590.209174899042;6677.666116694146;908.255230710824;908.6955869783696;914.3827916156567;925.2967744514083;947.1097168661436;3592.263585964871;3693.4090545513127;3735.402335626287;3738;3757.718171137121;12825.23048617086;13491.190528568317;13492.325383730775;13498.122622029538;13557.322133893265;12839.743205136032;13395.531156409479;13406.396712147976;13412.39644215993;13424.127431716906;9777.804443911082;10062.993700629879;10069.79860402788;10075.19398448137;10084.193264538819;6171.47542458896;6434.292227855026;6475.838104047437;6487.44856687168;6501.284858665448;10778;10835.945820270848;11163.218574700019;12137.635870923887;12160.574379896661;12181.025517958366;12202.32887191213;12298.385080745747;12408.937955310166;12457.875421245672;8216.876746848682;8383.790405239917;8384.371172161991;8396.076431592643;8400.579971001547;9966.302358834811;10140.695779126638;10267.897321026694;10277.897221027994;10292.485375731334;7597.012388389474;8517;8595.65617375323;8676.219140277406;8678.82642347162;8471.152884711479;8529.3602979778;8533.829323413622;8598.82802343962;8719.65121395137;7780.416468764978;7858.528488290721;7959.323457506177;7977.521348719096;7977.760667179994;5694.202811606296;5700.173474846207;5886.381929897299;5937.020391635505;5949.196858424058;5784;5871.970640146772;5884.617499862496;5896.852578685567;6007.098935159761 09:57:04 INFO - PROCESS | 1643 | |4;prototype.html;359.47337151073685;370.1025014340225;375.3356558890762;378.02469628358836;382.42636045931096;4533.116042371735;5027.648064635477;5144.379520451055;5154.32993710818;5163.173892177252;4138.565450627686;4304.268274393353;4511.834319526629;4780.545848144421;5061.44324124346;3631.237440137581;3838.4090738751233;4089.243489954353;4279.893002674927;4297.441332626754;3910.335243008681;4136.21411931732;4293.076988447483;4315.309550471925;4345.957400022;4159.542450330461;4246.214450326684;4271.466066741657;4280.807363668642;4292;4132.574261879647;4137.9172416551655;4172.228137794503;4219.7046206765535;4247.978760106211;3492.388831954398;3862.0731024553907;3894.631144060396;3903;3918.5101862267215;4038.1519880825063;4472.373867658531;4593.415271731247;4735.976320118411;5028.094942910275;4103.537877273642;4722.811087556528;4850.878728031792;4917.877053073666;4928.901421971576;4818.7831547580445;5121.539061484447;5127.384713834326;5277.47225277473;5327.46725327468;3944.8816535503693;3985.8206380712936;4032.9798351008058;4038.5759495253164;4325.366691978753;4658.720476771405;4871.269309603517;5102.744862756847;5216.608754343401;5438.782448702047;3695.9856878001556;4453.42105526286;4456.618448281043;4457;4468.843590474317;3856.5950575189763;4514.810316996254;4541.795619197144;4569.13186494565;4591.403117594691;4437.955620443819;4875.463698993108;4966.478519755374;4972.905960688643;5006.974965125151;4848.903021939542;5065.644475318994;5177.611679124081;5209.921851172236;5441.347038355343;1452.9055611385227;1539.7143385273198;1545.0806769971848;1551.7517197248385;1563.3746501399303;983.9163671088038;1010.5957616953277;1130.9773804524027;1151.8675352334421;1172.2497601535024;4259.424977628042;5051.545360917535;5133.9743301284;5171.301874247004;5206.817761378334;5141.460146684544;5153.768080436389;5701.4298570142655;5715.057015592381;5739.454751798572;4800.975995120072;4951.628627852925;5077.543021128116;5157.974210128926;5169.405518365361;2564.858932758716;2569.2934443028316;2742.969019233758;2846.7616586784816;2899.6810350861388;4901.656884018156;5781.653100813965;5872.7357268923;5884.352721200665;5910.19265848727;5522.392536820947;5577.414371491016;5602.663840169603;5756.625819321731;5792.119280082271;4833.468318485035;5172.793088276502;5356.732163391893;5666.433356664383;5732.512736417368;4847.491013443679;5643.548516118783;5666.858328541654;5676.659400435987;5684.669285592887;4823.734694591831;4909.533594308535;4983.436842657517;5606.878624275079;5653.106209419891;4768.880777980578;4871.5128487151005;5094.7962081516325;5518;5600.635958662674;4161.334186530141;4187.5184353799705;4389.582989615981;4584.5186255442695;4610.2854057621125;4458.286674132123;4743.905121897612;5397.325334333208;5523.6685798852195;5843.474087332161;4762.76186190696;5360.463953604608;5422.864428389322;5483.945160548344;5548.528375088002;5487.890242195213;5488.725563721878;5492.697901615449;5500.367457742411;5571.721413929368;5007.649464537593;5032.773525191302;5069.833938194162;5298.3906850712665;5380.139177731544;4723.834665786819;4899.289603007473;5497.505224529811;5530.55755539564;5534.418886016911;4506.977465112785;4727.787249573847;5319.813806516753;5340.2256672783005;5341.359036915595 09:57:04 INFO - PROCESS | 1643 | |5;yui.html;719.7444907057998;755.2409828122736;768.4083255892963;818.5866137600511;837.401258100458;14990.925045374785;15031.924840375796;15788.131652759093;15906.443274485395;15922.363105475768;14230.78653820191;14392.208428536427;14581.416743330254;14719.337629806658;14736.263186840642;928.9396189247711;945.1446440970898;945.7021038372891;947.4883562876073;955.3599088610613;926.3515539122608;940.3417607674621;941.7363138321247;950.1638558068889;963.6530848894392;995.8705368302111;1000;1001.159026417809;1002.939823610582;1003.6286573967606;993.8757655293089;996.1383403356117;997.8104160209537;998.9150922171624;999.1357475783432;611.3763960760044;741.3846507398846;741.6625435426868;742.0020073001804;742.532204711034;14090.365933532914;14325.64185895361;15436.922815385964;15769.132697701622;15839.920800395925;11993.64019079429;13958.302084895817;14169;14242.786358204636;14332.283385830668;11518.424078796026;11684.415779211005;11763.941180294043;11826.349550774705;11961.40192990347;3603.9819800900827;3668.981655091734;3702.1114932416294;3824.0631045394057;3844.673202777767;109682;110433;112748;116427.59648219345;116989.41505292505;10750.677479675622;10774.084202842847;10813.297135686154;10880.67357979262;11375.089992800557;22316.330510084397;22979.310620681405;22986.655200172012;23048.884755576113;23070.884645576665;7696.6536505857375;7720.691172353043;7786.805329866798;7850.685972561034;8034.638441270157;3776.4335349697767;4139.606737359946;4273.18809426208;4470.508244093147;4501.662375321864;3329.933401332008;3362.747793915466;3373.1735724747596;3431.5024321473247;3435.519027336175;620.798240571816;624.4224092714221;631.1479502671449;636.4335741190345;639.1339734659563;608.3217212807688;627.4447114303874;645.3772109913956;647.9287278399372;648.8313038609996;8044.833499142592;8060.516369017878;9766.853497197548;9828.950855245821;9987.60049598008;569.8034128330473;573.9913901291484;650.7592190889306;668.879601671694;673.8113328268092;4395.208862404734;4762.095201911626;4850.7574621269505;5294.814681486129;5423.29497165366;83373;90376;105760.47119764506;111055;111622;53884.73057634687;59776.60024379911;63309.683451582445;90275;90492.6426103609;79519.20480795117;81904.18095819085;83164.58417707872;84264.15735842563;84548.57725711333;10508.684739457829;10934.398608076313;11038.227324087236;11139.10887129044;11165.944170279097;46937.53062469332;50339;50519.74740126276;51318.23022654738;51648.19231326885;63352.68323658536;64314.67842660757;64717.67641161764;65049.34950650622;65923.01115483367;11013.504392302206;12289.815652765394;12398.318092504638;12632.621021369374;13127.081104322606;13656.522021729192;14004.929975350466;14617.488387906373;14736.336864840894;15127.941044127245;12822.615321540368;13585.52450664222;13783.931080344533;14006.509772157926;14165.787513187104;12555.497780088694;13608.727825443633;13632.182069075887;13632.318384080954;13856.722865542431;12341.382930853242;13404.36296538795;13531.526396576073;13840;13851;9527.428354298765;9976.950115249667;10103.54534045955;10205.795884082425;10301.072903438726;8109.635066421907;10093.848592271268;10597.788044239227;11203.327800332007;11261.7747645045 09:57:04 INFO - PROCESS | 1643 | __end_tp_report 09:57:04 INFO - PROCESS | 1643 | __start_cc_report 09:57:04 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1850 09:57:04 INFO - PROCESS | 1643 | __end_cc_report 09:57:04 INFO - PROCESS | 1643 | __startTimestamp1490633824977__endTimestamp 09:57:04 INFO - PROCESS | 1643 | 09:57:04 INFO - PROCESS | 1643 | ------- Summary: start ------- 09:57:04 INFO - PROCESS | 1643 | Number of tests: 6 09:57:04 INFO - PROCESS | 1643 | 09:57:04 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5917.51 Median:6176.41 stddev:685.41 (11.1%) stddev-sans-first:635.61 09:57:04 INFO - PROCESS | 1643 | Values: 2582.0 3268.3 3430.8 3438.5 3457.5 5979.7 6010.6 6031.9 6051.9 6084.4 5962.1 6003.6 6006.8 6016.1 6026.4 4362.5 5292.3 5340.9 5376.4 5477.4 5254.2 5263.5 5318.6 5350.8 5460.1 5344.1 5359.0 5372.0 5376.6 5383.4 5332.0 5332.3 5346.4 5348.0 5370.6 5206.5 5309.6 5311.3 5318.5 5351.3 5942.2 5955.3 5969.6 5975.1 5977.3 6318.1 6319.5 6333.2 6341.4 6344.4 6292.9 6317.3 6364.6 6411.8 6412.0 6057.7 6151.7 6161.2 6171.4 6207.5 6262.7 6278.3 6614.2 6628.4 6634.5 6233.0 6239.8 6273.9 6296.7 6335.5 6450.9 6459.9 6465.3 6468.1 6471.7 6359.7 6363.8 6365.6 6377.3 6379.3 5228.5 6142.4 6217.2 6287.1 6288.6 5106.0 5231.5 5243.4 5255.0 5257.7 4630.9 4659.6 4659.9 4673.6 4702.2 5627.1 5767.5 5778.3 5788.9 6062.2 6259.1 6276.7 6298.0 6315.0 6316.1 6108.2 6123.4 6125.1 6132.8 6137.4 5715.7 5719.2 5727.1 5730.5 5750.7 6660.7 6709.3 6710.0 6727.0 6731.5 6256.8 6274.1 6292.2 6316.4 6320.2 6408.3 6408.4 6507.8 6535.9 6612.4 6342.8 6353.2 6363.4 6364.1 6380.7 5812.8 6416.1 6435.3 6439.7 6455.1 5513.4 6160.5 6176.4 6203.9 6294.7 6268.6 6274.3 6280.6 6282.7 6300.3 6203.7 6280.7 6298.7 6305.0 6307.7 6111.4 6164.8 6198.4 6215.6 6293.3 5976.0 6282.3 6299.1 6302.7 6326.3 09:57:04 INFO - PROCESS | 1643 | 09:57:04 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:58021.49 Median:34503.07 stddev:119981.63 (347.7%) stddev-sans-first:120241.14 09:57:04 INFO - PROCESS | 1643 | Values: 744.4 792.4 813.6 868.2 881.4 27222.0 39202.6 39720.6 39745.6 39850.4 40417.4 40519.0 40572.6 40641.2 40677.6 7324.5 7676.5 9191.7 9195.6 9200.1 7300.8 10830.4 11059.5 11161.8 11180.7 10972.7 10975.0 11006.1 11013.4 11060.9 10971.8 10987.5 11007.8 11010.1 11031.9 7176.6 7354.4 7387.7 7401.5 7410.2 38155.2 38470.8 38545.2 38580.4 38694.2 3832.0 3833.3 3857.4 3876.8 3890.4 26455.6 26458.0 26822.9 27870.6 27912.7 2815.9 2821.8 2843.1 3020.7 3027.3 367000.0 367120.0 367359.0 368580.2 369124.0 51507.5 51668.2 51714.4 51718.0 51745.2 53275.2 53534.5 53589.5 53624.7 53643.7 14660.3 14785.6 14806.8 14809.4 14820.3 10874.9 12705.9 12706.3 12711.0 12717.8 10098.5 10159.0 13729.3 13970.8 14009.3 5710.9 5979.7 5982.5 6020.2 6028.2 827.0 959.6 975.1 977.3 996.8 28606.0 29368.4 29730.7 29982.6 30011.8 1034.5 1041.9 1061.2 1071.5 1073.5 13794.9 13815.5 14854.0 15230.5 15279.7 503278.0 504585.0 505507.0 507159.0 508533.0 56014.0 56359.0 56466.7 56555.4 56590.7 1412.8 1447.2 1454.8 1461.5 1463.6 26694.5 26932.9 28285.4 28316.7 28978.6 45126.3 45588.5 45597.1 45690.8 45716.0 467381.0 468047.0 468230.0 468398.0 469338.0 32854.3 34506.7 34955.0 35235.8 35344.6 30252.5 40686.2 42674.1 44186.3 45391.8 29744.7 30097.8 30112.0 30521.5 30750.0 24220.8 29501.0 29780.1 30325.1 30361.7 32280.2 32685.0 34391.0 34499.5 36230.3 27373.5 28219.0 28719.4 29056.0 29113.7 22598.2 22643.3 23079.3 23167.5 23174.8 09:57:04 INFO - PROCESS | 1643 | 09:57:04 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:43140.26 Median:13831.62 stddev:125034.44 (904.0%) stddev-sans-first:125344.02 09:57:04 INFO - PROCESS | 1643 | Values: 407.5 413.8 419.6 429.4 446.4 18580.3 19766.3 20977.2 20995.5 21076.0 14505.0 21958.0 22294.4 22577.1 22577.9 6317.7 6642.5 6650.7 6654.9 6664.2 6260.2 6502.6 6557.7 6563.5 6581.0 6321.6 6527.2 6555.8 6566.6 6567.5 6494.8 6565.1 6607.3 6619.2 6620.9 6478.7 6488.7 6494.2 6615.3 6894.9 25204.7 25290.6 25416.7 25594.7 25704.7 2458.5 2833.3 2862.3 2917.4 2927.2 15269.7 15363.3 15389.2 16084.6 16509.6 1575.7 1999.7 2012.3 2038.3 2045.4 148167.0 164821.0 171755.0 177780.0 178295.1 20072.2 20192.7 20263.3 20303.4 20308.7 31633.7 32236.7 32284.4 32337.4 32344.2 8640.7 9029.2 9155.8 9165.3 10056.7 6784.9 7601.0 7605.2 7620.3 7623.0 6554.3 8277.9 9193.2 9196.2 9209.5 3838.5 3917.6 3940.9 3952.4 3958.9 880.9 882.0 883.6 921.6 924.3 20244.5 21141.4 21478.0 21636.8 21659.5 903.6 909.2 928.6 933.2 934.9 8601.9 10667.3 11074.9 11209.4 11213.3 694019.0 728319.0 764176.0 766932.3 775840.0 133947.0 139978.6 161783.0 162814.0 166679.0 1156.3 1174.8 1184.9 1197.5 1200.0 17713.4 18684.3 18689.6 18711.6 18723.4 58808.1 62963.7 63252.0 63508.0 64057.4 72218.0 91643.5 94059.5 94475.9 94782.0 10891.2 11319.2 11427.7 11429.8 11825.7 12416.6 12576.7 12679.2 12743.9 12799.9 10487.2 11048.7 11160.9 11218.4 11250.2 10142.7 10267.3 10288.6 10446.1 10533.2 11081.2 11171.1 11404.3 11405.1 11444.7 11368.0 11510.7 11539.3 11646.4 11780.2 10471.4 10672.1 10917.0 11132.3 11177.9 09:57:04 INFO - PROCESS | 1643 | 09:57:04 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6565.00 Median:2405.87 stddev:4063.63 (168.9%) stddev-sans-first:4057.46 09:57:04 INFO - PROCESS | 1643 | Values: 1535.7 1551.0 1563.2 1593.6 1597.2 10446.3 10985.2 10988.1 10997.2 11007.0 10525.7 10949.8 11014.6 11024.6 11025.2 2367.7 2400.4 2405.4 2406.4 2410.3 2301.0 2378.1 2379.4 2384.5 2397.2 2305.2 2368.8 2372.0 2389.9 2394.6 2308.3 2364.4 2365.1 2369.6 2374.2 2353.4 2375.5 2378.5 2381.8 2382.4 10481.0 10698.6 10704.0 10704.8 10728.5 5694.4 5868.8 5870.6 5873.3 5887.2 3454.6 3491.4 3562.4 3596.3 3655.3 1139.5 1158.2 1198.8 1202.3 1205.6 13521.2 13864.9 14099.8 15066.8 15285.2 4568.7 4713.3 4742.4 4743.3 4746.0 10128.8 10382.9 11541.9 11565.1 11582.6 5477.6 5629.4 5657.4 5663.3 5673.5 5153.6 5180.5 5206.0 5226.8 5231.7 3796.2 3803.9 3839.9 3877.0 3890.5 1021.1 1021.2 1021.8 1035.4 1040.3 908.9 920.8 934.8 938.7 979.2 6415.9 6429.3 6572.5 6590.2 6677.7 908.3 908.7 914.4 925.3 947.1 3592.3 3693.4 3735.4 3738.0 3757.7 12825.2 13491.2 13492.3 13498.1 13557.3 12839.7 13395.5 13406.4 13412.4 13424.1 9777.8 10063.0 10069.8 10075.2 10084.2 6171.5 6434.3 6475.8 6487.4 6501.3 10778.0 10835.9 11163.2 12137.6 12160.6 12181.0 12202.3 12298.4 12408.9 12457.9 8216.9 8383.8 8384.4 8396.1 8400.6 9966.3 10140.7 10267.9 10277.9 10292.5 7597.0 8517.0 8595.7 8676.2 8678.8 8471.2 8529.4 8533.8 8598.8 8719.7 7780.4 7858.5 7959.3 7977.5 7977.8 5694.2 5700.2 5886.4 5937.0 5949.2 5784.0 5872.0 5884.6 5896.9 6007.1 09:57:04 INFO - PROCESS | 1643 | 09:57:04 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4496.91 Median:4849.89 stddev:1251.35 (25.8%) stddev-sans-first:1215.71 09:57:04 INFO - PROCESS | 1643 | Values: 359.5 370.1 375.3 378.0 382.4 4533.1 5027.6 5144.4 5154.3 5163.2 4138.6 4304.3 4511.8 4780.5 5061.4 3631.2 3838.4 4089.2 4279.9 4297.4 3910.3 4136.2 4293.1 4315.3 4346.0 4159.5 4246.2 4271.5 4280.8 4292.0 4132.6 4137.9 4172.2 4219.7 4248.0 3492.4 3862.1 3894.6 3903.0 3918.5 4038.2 4472.4 4593.4 4736.0 5028.1 4103.5 4722.8 4850.9 4917.9 4928.9 4818.8 5121.5 5127.4 5277.5 5327.5 3944.9 3985.8 4033.0 4038.6 4325.4 4658.7 4871.3 5102.7 5216.6 5438.8 3696.0 4453.4 4456.6 4457.0 4468.8 3856.6 4514.8 4541.8 4569.1 4591.4 4438.0 4875.5 4966.5 4972.9 5007.0 4848.9 5065.6 5177.6 5209.9 5441.3 1452.9 1539.7 1545.1 1551.8 1563.4 983.9 1010.6 1131.0 1151.9 1172.2 4259.4 5051.5 5134.0 5171.3 5206.8 5141.5 5153.8 5701.4 5715.1 5739.5 4801.0 4951.6 5077.5 5158.0 5169.4 2564.9 2569.3 2743.0 2846.8 2899.7 4901.7 5781.7 5872.7 5884.4 5910.2 5522.4 5577.4 5602.7 5756.6 5792.1 4833.5 5172.8 5356.7 5666.4 5732.5 4847.5 5643.5 5666.9 5676.7 5684.7 4823.7 4909.5 4983.4 5606.9 5653.1 4768.9 4871.5 5094.8 5518.0 5600.6 4161.3 4187.5 4389.6 4584.5 4610.3 4458.3 4743.9 5397.3 5523.7 5843.5 4762.8 5360.5 5422.9 5483.9 5548.5 5487.9 5488.7 5492.7 5500.4 5571.7 5007.6 5032.8 5069.8 5298.4 5380.1 4723.8 4899.3 5497.5 5530.6 5534.4 4507.0 4727.8 5319.8 5340.2 5341.4 09:57:04 INFO - PROCESS | 1643 | 09:57:05 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20171.93 Median:3685.55 stddev:29149.06 (790.9%) stddev-sans-first:29194.23 09:57:05 INFO - PROCESS | 1643 | Values: 719.7 755.2 768.4 818.6 837.4 14990.9 15031.9 15788.1 15906.4 15922.4 14230.8 14392.2 14581.4 14719.3 14736.3 928.9 945.1 945.7 947.5 955.4 926.4 940.3 941.7 950.2 963.7 995.9 1000.0 1001.2 1002.9 1003.6 993.9 996.1 997.8 998.9 999.1 611.4 741.4 741.7 742.0 742.5 14090.4 14325.6 15436.9 15769.1 15839.9 11993.6 13958.3 14169.0 14242.8 14332.3 11518.4 11684.4 11763.9 11826.3 11961.4 3604.0 3669.0 3702.1 3824.1 3844.7 109682.0 110433.0 112748.0 116427.6 116989.4 10750.7 10774.1 10813.3 10880.7 11375.1 22316.3 22979.3 22986.7 23048.9 23070.9 7696.7 7720.7 7786.8 7850.7 8034.6 3776.4 4139.6 4273.2 4470.5 4501.7 3329.9 3362.7 3373.2 3431.5 3435.5 620.8 624.4 631.1 636.4 639.1 608.3 627.4 645.4 647.9 648.8 8044.8 8060.5 9766.9 9829.0 9987.6 569.8 574.0 650.8 668.9 673.8 4395.2 4762.1 4850.8 5294.8 5423.3 83373.0 90376.0 105760.5 111055.0 111622.0 53884.7 59776.6 63309.7 90275.0 90492.6 79519.2 81904.2 83164.6 84264.2 84548.6 10508.7 10934.4 11038.2 11139.1 11165.9 46937.5 50339.0 50519.7 51318.2 51648.2 63352.7 64314.7 64717.7 65049.3 65923.0 11013.5 12289.8 12398.3 12632.6 13127.1 13656.5 14004.9 14617.5 14736.3 15127.9 12822.6 13585.5 13783.9 14006.5 14165.8 12555.5 13608.7 13632.2 13632.3 13856.7 12341.4 13404.4 13531.5 13840.0 13851.0 9527.4 9977.0 10103.5 10205.8 10301.1 8109.6 10093.8 10597.8 11203.3 11261.8 09:57:05 INFO - PROCESS | 1643 | -------- Summary: end -------- 09:57:05 INFO - PROCESS | 1643 | 09:57:05 INFO - TEST-INFO | 1643: exit 0 09:57:05 INFO - TEST-OK | dromaeo_css | took 1120241ms 09:57:05 INFO - TEST-START | kraken 09:57:05 INFO - operating with platform_type : mac_ 09:57:05 INFO - Initialising browser for kraken test... 09:57:05 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpckDfYC/profile http://localhost:49248/getInfo.html) 09:57:07 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:57:07 INFO - PROCESS | 1653 | colorDepth:24 09:57:07 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:57:07 INFO - PROCESS | 1653 | __metrics 09:57:08 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:57:08 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:08 INFO - TEST-INFO | 1653: exit 0 09:57:08 INFO - Browser initialized. 09:57:08 INFO - Running cycle 1/1 for kraken test... 09:57:08 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpckDfYC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:20 INFO - PROCESS | 1654 | RSS: Main: 214933504 09:57:20 INFO - PROCESS | 1654 | 09:57:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:25 INFO - PROCESS | 1654 | RSS: Main: 267997184 09:57:25 INFO - PROCESS | 1654 | 09:57:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 09:57:28 INFO - PROCESS | 1654 | RSS: Main: 278732800 09:57:28 INFO - PROCESS | 1654 | 09:57:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 09:57:32 INFO - PROCESS | 1654 | RSS: Main: 375808000 09:57:32 INFO - PROCESS | 1654 | 09:57:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 09:57:34 INFO - PROCESS | 1654 | RSS: Main: 277901312 09:57:34 INFO - PROCESS | 1654 | 09:57:36 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:37 INFO - PROCESS | 1654 | RSS: Main: 279216128 09:57:37 INFO - PROCESS | 1654 | 09:57:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:41 INFO - PROCESS | 1654 | RSS: Main: 317497344 09:57:41 INFO - PROCESS | 1654 | 09:57:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:44 INFO - PROCESS | 1654 | RSS: Main: 320487424 09:57:44 INFO - PROCESS | 1654 | 09:57:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 09:57:48 INFO - PROCESS | 1654 | RSS: Main: 318971904 09:57:48 INFO - PROCESS | 1654 | 09:57:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:50 INFO - PROCESS | 1654 | RSS: Main: 294174720 09:57:50 INFO - PROCESS | 1654 | 09:57:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:52 INFO - PROCESS | 1654 | RSS: Main: 297869312 09:57:52 INFO - PROCESS | 1654 | 09:57:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:55 INFO - PROCESS | 1654 | RSS: Main: 304549888 09:57:55 INFO - PROCESS | 1654 | 09:57:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:59 INFO - PROCESS | 1654 | RSS: Main: 318627840 09:57:59 INFO - PROCESS | 1654 | 09:58:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:58:03 INFO - PROCESS | 1654 | RSS: Main: 313520128 09:58:03 INFO - PROCESS | 1654 | 09:58:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 09:58:05 INFO - PROCESS | 1654 | RSS: Main: 357900288 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | __start_tp_report 09:58:05 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:58:05 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:58:05 INFO - PROCESS | 1654 | |i|pagename|runs| 09:58:05 INFO - PROCESS | 1654 | |0;ai-astar;118;119;156;171;94;168;173;169;116;116 09:58:05 INFO - PROCESS | 1654 | |1;audio-beat-detection;137;109;110;140;111;110;109;109;130;110 09:58:05 INFO - PROCESS | 1654 | |2;audio-dft;204;263;186;183;186;185;186;183;188;186 09:58:05 INFO - PROCESS | 1654 | |3;audio-fft;99;76;83;115;60;63;62;61;75;62 09:58:05 INFO - PROCESS | 1654 | |4;audio-oscillator;88;69;87;69;69;68;82;67;67;84 09:58:05 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;89;89;89;88;93;88;88;89;88;88 09:58:05 INFO - PROCESS | 1654 | |6;imaging-darkroom;100;96;96;95;95;95;95;96;96;94 09:58:05 INFO - PROCESS | 1654 | |7;imaging-desaturate;84;83;83;87;87;86;83;91;86;83 09:58:05 INFO - PROCESS | 1654 | |8;json-parse-financial;62;62;62;62;64;64;65;63;63;63 09:58:05 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;55;61;62;65;61;58;59;59;58;59 09:58:05 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;85;84;78;95;100;86;81;81;88;88 09:58:05 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;158;161;128;144;137;132;140;139;175;148 09:58:05 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;164;162;165;181;218;210;164;166;165;166 09:58:05 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;66;67;66;65;65;64;63;61;63;64 09:58:05 INFO - PROCESS | 1654 | __end_tp_report 09:58:05 INFO - PROCESS | 1654 | __start_cc_report 09:58:05 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2143 09:58:05 INFO - PROCESS | 1654 | __end_cc_report 09:58:05 INFO - PROCESS | 1654 | __startTimestamp1490633885019__endTimestamp 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:58:05 INFO - PROCESS | 1654 | Number of tests: 14 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:140.00 Median:168.50 stddev:30.04 (17.8%) stddev-sans-first:30.79 09:58:05 INFO - PROCESS | 1654 | Values: 118.0 119.0 156.0 171.0 94.0 168.0 173.0 169.0 116.0 116.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:117.50 Median:110.50 stddev:12.78 (11.6%) stddev-sans-first:11.45 09:58:05 INFO - PROCESS | 1654 | Values: 137.0 109.0 110.0 140.0 111.0 110.0 109.0 109.0 130.0 110.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:195.00 Median:186.00 stddev:24.64 (13.2%) stddev-sans-first:25.92 09:58:05 INFO - PROCESS | 1654 | Values: 204.0 263.0 186.0 183.0 186.0 185.0 186.0 183.0 188.0 186.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:75.60 Median:69.00 stddev:18.68 (27.1%) stddev-sans-first:17.79 09:58:05 INFO - PROCESS | 1654 | Values: 99.0 76.0 83.0 115.0 60.0 63.0 62.0 61.0 75.0 62.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:75.50 stddev:8.99 (11.9%) stddev-sans-first:8.22 09:58:05 INFO - PROCESS | 1654 | Values: 88.0 69.0 87.0 69.0 69.0 68.0 82.0 67.0 67.0 84.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:89.00 stddev:1.52 (1.7%) stddev-sans-first:1.62 09:58:05 INFO - PROCESS | 1654 | Values: 89.0 89.0 89.0 88.0 93.0 88.0 88.0 89.0 88.0 88.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:95.50 stddev:1.62 (1.7%) stddev-sans-first:0.71 09:58:05 INFO - PROCESS | 1654 | Values: 100.0 96.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 94.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:86.00 stddev:2.63 (3.1%) stddev-sans-first:2.74 09:58:05 INFO - PROCESS | 1654 | Values: 84.0 83.0 83.0 87.0 87.0 86.0 83.0 91.0 86.0 83.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.00 stddev:1.05 (1.7%) stddev-sans-first:1.05 09:58:05 INFO - PROCESS | 1654 | Values: 62.0 62.0 62.0 62.0 64.0 64.0 65.0 63.0 63.0 63.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:2.71 (4.5%) stddev-sans-first:2.28 09:58:05 INFO - PROCESS | 1654 | Values: 55.0 61.0 62.0 65.0 61.0 58.0 59.0 59.0 58.0 59.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:85.50 stddev:6.67 (7.8%) stddev-sans-first:7.05 09:58:05 INFO - PROCESS | 1654 | Values: 85.0 84.0 78.0 95.0 100.0 86.0 81.0 81.0 88.0 88.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:146.20 Median:146.00 stddev:14.54 (10.0%) stddev-sans-first:14.79 09:58:05 INFO - PROCESS | 1654 | Values: 158.0 161.0 128.0 144.0 137.0 132.0 140.0 139.0 175.0 148.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:166.00 stddev:20.74 (12.5%) stddev-sans-first:21.53 09:58:05 INFO - PROCESS | 1654 | Values: 164.0 162.0 165.0 181.0 218.0 210.0 164.0 166.0 165.0 166.0 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:1.78 (2.7%) stddev-sans-first:1.79 09:58:05 INFO - PROCESS | 1654 | Values: 66.0 67.0 66.0 65.0 65.0 64.0 63.0 61.0 63.0 64.0 09:58:05 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:58:05 INFO - PROCESS | 1654 | 09:58:05 INFO - TEST-INFO | 1654: exit 0 09:58:05 INFO - TEST-OK | kraken | took 60322ms 09:58:05 INFO - SUITE-END | took 1180s 09:58:06 INFO - Completed test suite (00:19:40) 09:58:06 INFO - javascript benchmark 09:58:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7591.300752305065, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2581.9801178534485, 3268.2646404558964, 3430.828458577071, 3438.487065691097, 3457.4813777933323, 5979.671118088503, 6010.6093103948215, 6031.879362412749, 6051.939480605198, 6084.421979911913, 5962.10568414738, 6003.579749417542, 6006.7597296108215, 6016.127661489092, 6026.397360263972, 4362.454693163355, 5292.259083728281, 5340.91988620171, 5376.435474275184, 5477.397486276506, 5254.185601231796, 5263.473652634744, 5318.627696061278, 5350.839474815762, 5460.099083651193, 5344.144936810111, 5359.0, 5371.973140134313, 5376.596755243353, 5383.353997520303, 5331.973340133274, 5332.306800115999, 5346.4118946915805, 5347.983883062206, 5370.624056316079, 5206.479352064801, 5309.5752339812725, 5311.309529761106, 5318.468153184689, 5351.25082488452, 5942.197803296543, 5955.34491205967, 5969.5821292509545, 5975.1037344398255, 5977.312609049972, 6318.083877837687, 6319.4944404448, 6333.24001119864, 6341.429271365553, 6344.3655634436645, 6292.937070629327, 6317.273513545909, 6364.586301890362, 6411.8397040073905, 6411.96794016027, 6057.727402266909, 6151.661658608775, 6161.229846269216, 6171.413715697002, 6207.534434917354, 6262.6868656566985, 6278.30938596754, 6614.2393624733295, 6628.403443689995, 6634.469242460592, 6233.0, 6239.844003899939, 6273.937260627426, 6296.748130074838, 6335.524835637253, 6450.903236451457, 6459.870802583922, 6465.288818229991, 6468.126802881645, 6471.6764161791725, 6359.713812878432, 6363.840903977438, 6365.618062916147, 6377.330380310001, 6379.330170332141, 5228.4510126436935, 6142.406692085091, 6217.191765070603, 6287.088372186007, 6288.591241569284, 5106.02985432774, 5231.450697676765, 5243.370795504564, 5255.02781985334, 5257.710825904535, 4630.934884976475, 4659.5573420525, 4659.860204193879, 4673.556012178838, 4702.153612349742, 5627.071533197059, 5767.509761670221, 5778.306603207558, 5788.90010897928, 6062.242219722535, 6259.123722678829, 6276.717547710364, 6298.0, 6315.021171718391, 6316.115743795872, 6108.175396321529, 6123.4351415035735, 6125.081237814275, 6132.846678832976, 6137.41694539018, 5715.657060576379, 5719.19931209631, 5727.140928860621, 5730.484256416942, 5750.683712395775, 6660.733570657313, 6709.329067093252, 6709.993500974795, 6726.966365168143, 6731.46148308144, 6256.812295631138, 6274.090256912813, 6292.18201633803, 6316.431521163117, 6320.241571011508, 6408.263049749152, 6408.359164083741, 6507.804765857032, 6535.869282614416, 6612.371824676648, 6342.841428964312, 6353.237611486651, 6363.3636636336, 6364.109024736633, 6380.712867920862, 5812.779316343446, 6416.13382193398, 6435.292117867125, 6439.7424103035355, 6455.0640157176, 5513.393526711979, 6160.507159427146, 6176.413240742122, 6203.875922481435, 6294.716737746904, 6268.623882566878, 6274.309825919054, 6280.591761535486, 6282.685865706788, 6300.2754683212015, 6203.689815509297, 6280.717367718372, 6298.685065746786, 6305.0, 6307.653079080511, 6111.40942266768, 6164.845878853064, 6198.442140207403, 6215.627062376272, 6293.307736149111, 5976.0, 6282.277538083179, 6299.149614802126, 6302.716377763103, 6326.3041065483685], "value": 5870.750471829708, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.4044764188651, 792.3542313014892, 813.6094674556215, 868.2099289646424, 881.3742243007464, 27222.0, 39202.607973920254, 39720.602793972124, 39745.602543974586, 39850.40224396636, 40417.393739093866, 40518.98702532438, 40572.59427405725, 40641.187176256455, 40677.59322406775, 7324.48728588999, 7676.5394076355315, 9191.724248272563, 9195.586198621044, 9200.079992000814, 7300.780976570712, 10830.40432776197, 11059.50232239547, 11161.776764464707, 11180.720481987933, 10972.725681857977, 10975.012248897598, 11006.073937061023, 11013.394263315515, 11060.889391106106, 10971.780564388708, 10987.450627468674, 11007.779844403147, 11010.119190464784, 11031.944840275748, 7176.564687062556, 7354.374878135365, 7387.741429050011, 7401.481896267263, 7410.221926697727, 38155.23689526222, 38470.80764596187, 38545.22909541793, 38580.421293680614, 38694.22611547782, 3832.0419895026243, 3833.310004199243, 3857.4406711026745, 3876.8255428505786, 3890.3580909149955, 26455.603165952525, 26458.0, 26822.865885670642, 27870.5819412709, 27912.720872791215, 2815.9296017599518, 2821.84479853608, 2843.1470558832475, 3020.7432368248724, 3027.273454370957, 367000.0, 367120.0, 367359.0, 368580.1570992155, 369124.0, 51507.48492515102, 51668.22497662463, 51714.37999340086, 51718.0, 51745.22382164271, 53275.20087198695, 53534.464655352946, 53589.46410535923, 53624.73187634037, 53643.731781340844, 14660.340284687214, 14785.630359241106, 14806.777898331533, 14809.407623694931, 14820.25898705082, 10874.945625271823, 12705.872941270654, 12706.30115343665, 12711.046671499675, 12717.809232861515, 10098.495075246208, 10158.98410158993, 13729.313534323244, 13970.790438143436, 14009.299535023412, 5710.942890571124, 5979.730912108966, 5982.521398288127, 6020.217371741645, 6028.156058151862, 827.0, 959.5633986536109, 975.1029053271008, 977.2719324103589, 996.8255555277798, 28606.0, 29368.412631747673, 29730.702692973227, 29982.55026174609, 30011.849940750155, 1034.5448002878709, 1041.8645576075214, 1061.2200032975757, 1071.5392381276126, 1073.5437439088357, 13794.862051379558, 13815.516456923959, 14854.0, 15230.543083707507, 15279.694406111816, 503278.0, 504585.0, 505507.0, 507159.0, 508533.0, 56014.0, 56358.99999999918, 56466.71766641141, 56555.43444565502, 56590.7170464145, 1412.7527682655493, 1447.1823419767798, 1454.8254209494928, 1461.5250043735616, 1463.5536161470854, 26694.466110678066, 26932.865335673196, 28285.43429131447, 28316.716832831407, 28978.565321520615, 45126.32310515279, 45588.54411455976, 45597.088058239315, 45690.77154614206, 45716.0, 467381.0, 468047.0, 468230.0, 468398.0, 469338.0, 32854.34291314208, 34506.65493345135, 34954.951351459495, 35235.82382088073, 35344.64655353517, 30252.54621180728, 40686.1862762749, 42674.146517070105, 44186.33720494131, 45391.773041134584, 29744.702552974195, 30097.84951075318, 30112.0, 30521.542176866922, 30750.0, 24220.75779242185, 29500.967466138583, 29780.106596802132, 30325.090247292617, 30361.69638303677, 32280.192995175308, 32685.019449416555, 34391.0, 34499.4825077619, 36230.27539449144, 27373.494457805013, 28219.012334568193, 28719.42561148807, 29055.983040593477, 29113.708862911946, 22598.209062682727, 22643.320700379016, 23079.307620771404, 23167.53664926726, 23174.768252317936], "value": 18537.588208171248, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.49063670411965, 413.7765606572451, 419.5992826850358, 429.35382249714183, 446.3862189489452, 18580.349687760914, 19766.30817921373, 20977.16091356348, 20995.47511312218, 21076.0, 14505.0, 21958.0, 22294.442638934033, 22577.096916123377, 22577.887110564465, 6317.715702793374, 6642.5350225484235, 6650.70071846768, 6654.900176497356, 6664.233613134503, 6260.217472815898, 6502.6098434093865, 6557.70490327934, 6563.54055216135, 6581.0, 6321.620702757827, 6527.151470308853, 6555.80332590023, 6566.63883486408, 6567.54027218095, 6494.837629059288, 6565.1137096492075, 6607.273199948026, 6619.238787539446, 6620.867582648369, 6478.708458119396, 6488.675566221717, 6494.220693516808, 6615.338466153395, 6894.896576551306, 25204.74795252042, 25290.620640690406, 25416.745832541623, 25594.74405255942, 25704.74295257061, 2458.483718419134, 2833.2916770807296, 2862.298736809474, 2917.416516696669, 2927.2096533935933, 15269.694606107927, 15363.308651110727, 15389.230538473032, 16084.59788505285, 16509.58726031847, 1575.6691094869977, 1999.7000449932482, 2012.3258708332726, 2038.3069756282787, 2045.4170561389933, 148167.0, 164821.0, 171755.0, 177780.0, 178295.10852445656, 20072.19711211535, 20192.69710954337, 20263.290784822755, 20303.39089827278, 20308.69536956947, 31633.734650614177, 32236.677633223837, 32284.35431291361, 32337.35325293481, 32344.191395215308, 8640.654373824977, 9029.23251523628, 9155.81688366229, 9165.266778657691, 10056.69829905104, 6784.932150678529, 7600.9619951900995, 7605.163432022473, 7620.276073772982, 7623.047119110111, 6554.345490076526, 8277.875831862528, 9193.172614464731, 9196.218321442619, 9209.539523023823, 3838.5009948707047, 3917.5690674025836, 3940.8620698275995, 3952.4268980998168, 3958.8614398495915, 880.8986966498807, 881.9647214111492, 883.5847151838625, 921.6129225725211, 924.2929159193222, 20244.493887652632, 21141.365759027252, 21478.0, 21636.78363216379, 21659.458513537287, 903.6249956268075, 909.2089881802742, 928.6471140966389, 933.2254228989922, 934.8878134623752, 8601.870971935426, 10667.306625069346, 11074.889251107386, 11209.43952802373, 11213.327200368005, 694019.0, 728319.0, 764176.0, 766932.3306766972, 775840.0, 133947.0, 139978.60021399654, 161783.0, 162814.0, 166679.0, 1156.259993604094, 1174.8355230267769, 1184.922980006297, 1197.4731118307743, 1199.9580014699443, 17713.380031699344, 18684.25262989465, 18689.626207476045, 18711.625767484846, 18723.378726978506, 58808.11787823272, 62963.6851815738, 63252.0, 63508.0, 64057.35942640514, 72218.0, 91643.54178229066, 94059.52970235379, 94475.86165843184, 94782.0, 10891.237613366988, 11319.151063670093, 11427.714307142389, 11429.828552571884, 11825.704357391134, 12416.627501174979, 12576.685582860502, 12679.23924564529, 12743.941454051146, 12799.936000319938, 10487.16102711766, 11048.723781905517, 11160.888391115985, 11218.382988935688, 11250.212485125963, 10142.695719128438, 10267.281290309607, 10288.588456461957, 10446.112080473245, 10533.157347412036, 11081.229692576855, 11171.050460710605, 11404.315741055232, 11405.144614153805, 11444.656660300205, 11367.976882080653, 11510.712232193928, 11539.307641541534, 11646.359450230319, 11780.234284771463, 10471.424071675827, 10672.146228301563, 10917.01532431396, 11132.276402033842, 11177.944110279397], "value": 11256.540413967929, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1535.6621543260478, 1550.9922450387746, 1563.1871426858033, 1593.6175317923694, 1597.217363491889, 10446.320989135711, 10985.231033827635, 10988.066014388787, 10997.230193886431, 11007.0, 10525.684229473109, 10949.781004379907, 11014.559417623286, 11024.614138505154, 11025.173112016611, 2367.7395486496516, 2400.423898264413, 2405.386626410263, 2406.362313986786, 2410.276916924924, 2301.045066297493, 2378.0725517048363, 2379.393254720044, 2384.523095380931, 2397.220903206456, 2305.2392710405525, 2368.834181607288, 2371.991903441039, 2389.880505974694, 2394.580948334043, 2308.319045881469, 2364.4443555764383, 2365.065799009382, 2369.620860662286, 2374.204641445118, 2353.435175557871, 2375.5486457573006, 2378.452955820154, 2381.7975472084895, 2382.3567636737985, 10481.0, 10698.62554810578, 10704.0, 10704.839427408595, 10728.517216725266, 5694.402087780765, 5868.823935281948, 5870.589058765888, 5873.324567674729, 5887.205227294303, 3454.63726308739, 3491.423915054013, 3562.447820587813, 3596.32074104791, 3655.305491956546, 1139.4587570903755, 1158.1834806461509, 1198.8441502604692, 1202.326697049655, 1205.6443349211916, 13521.188728676212, 13864.861351386558, 14099.78850317246, 15066.849331506653, 15285.235738213156, 4568.657350698711, 4713.34013238147, 4742.4309082909585, 4743.288506724019, 4745.976270118627, 10128.797424051625, 10382.948085259526, 11541.884581154249, 11565.074794016458, 11582.594609188638, 5477.589180811455, 5629.43705629442, 5657.377688454267, 5663.348714897757, 5673.517750991129, 5153.561947234527, 5180.4560521145495, 5205.9739701302005, 5226.817061402832, 5231.659942103751, 3796.240751849641, 3803.8668646597234, 3839.9232015359535, 3876.96123038766, 3890.513685789276, 1021.1473419694489, 1021.1932573267036, 1021.7905329407603, 1035.440861934562, 1040.3185913226698, 908.8591268353416, 920.7790130368663, 934.8223837470995, 938.7465384346189, 979.2459805949379, 6415.871682566416, 6429.32492088324, 6572.539922205496, 6590.209174899042, 6677.666116694146, 908.255230710824, 908.6955869783696, 914.3827916156567, 925.2967744514083, 947.1097168661436, 3592.263585964871, 3693.4090545513127, 3735.402335626287, 3738.0, 3757.718171137121, 12825.23048617086, 13491.190528568317, 13492.325383730775, 13498.122622029538, 13557.322133893265, 12839.743205136032, 13395.531156409479, 13406.396712147976, 13412.39644215993, 13424.127431716906, 9777.804443911082, 10062.993700629879, 10069.79860402788, 10075.19398448137, 10084.193264538819, 6171.47542458896, 6434.292227855026, 6475.838104047437, 6487.44856687168, 6501.284858665448, 10778.0, 10835.945820270848, 11163.218574700019, 12137.635870923887, 12160.574379896661, 12181.025517958366, 12202.32887191213, 12298.385080745747, 12408.937955310166, 12457.875421245672, 8216.876746848682, 8383.790405239917, 8384.371172161991, 8396.076431592643, 8400.579971001547, 9966.302358834811, 10140.695779126638, 10267.897321026694, 10277.897221027994, 10292.485375731334, 7597.012388389474, 8517.0, 8595.65617375323, 8676.219140277406, 8678.82642347162, 8471.152884711479, 8529.3602979778, 8533.829323413622, 8598.82802343962, 8719.65121395137, 7780.416468764978, 7858.528488290721, 7959.323457506177, 7977.521348719096, 7977.760667179994, 5694.202811606296, 5700.173474846207, 5886.381929897299, 5937.020391635505, 5949.196858424058, 5784.0, 5871.970640146772, 5884.617499862496, 5896.852578685567, 6007.098935159761], "value": 5026.815012549503, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.47337151073685, 370.1025014340225, 375.3356558890762, 378.02469628358836, 382.42636045931096, 4533.116042371735, 5027.648064635477, 5144.379520451055, 5154.32993710818, 5163.173892177252, 4138.565450627686, 4304.268274393353, 4511.834319526629, 4780.545848144421, 5061.44324124346, 3631.237440137581, 3838.4090738751233, 4089.243489954353, 4279.893002674927, 4297.441332626754, 3910.335243008681, 4136.21411931732, 4293.076988447483, 4315.309550471925, 4345.957400022, 4159.542450330461, 4246.214450326684, 4271.466066741657, 4280.807363668642, 4292.0, 4132.574261879647, 4137.9172416551655, 4172.228137794503, 4219.7046206765535, 4247.978760106211, 3492.388831954398, 3862.0731024553907, 3894.631144060396, 3903.0, 3918.5101862267215, 4038.1519880825063, 4472.373867658531, 4593.415271731247, 4735.976320118411, 5028.094942910275, 4103.537877273642, 4722.811087556528, 4850.878728031792, 4917.877053073666, 4928.901421971576, 4818.7831547580445, 5121.539061484447, 5127.384713834326, 5277.47225277473, 5327.46725327468, 3944.8816535503693, 3985.8206380712936, 4032.9798351008058, 4038.5759495253164, 4325.366691978753, 4658.720476771405, 4871.269309603517, 5102.744862756847, 5216.608754343401, 5438.782448702047, 3695.9856878001556, 4453.42105526286, 4456.618448281043, 4457.0, 4468.843590474317, 3856.5950575189763, 4514.810316996254, 4541.795619197144, 4569.13186494565, 4591.403117594691, 4437.955620443819, 4875.463698993108, 4966.478519755374, 4972.905960688643, 5006.974965125151, 4848.903021939542, 5065.644475318994, 5177.611679124081, 5209.921851172236, 5441.347038355343, 1452.9055611385227, 1539.7143385273198, 1545.0806769971848, 1551.7517197248385, 1563.3746501399303, 983.9163671088038, 1010.5957616953277, 1130.9773804524027, 1151.8675352334421, 1172.2497601535024, 4259.424977628042, 5051.545360917535, 5133.9743301284, 5171.301874247004, 5206.817761378334, 5141.460146684544, 5153.768080436389, 5701.4298570142655, 5715.057015592381, 5739.454751798572, 4800.975995120072, 4951.628627852925, 5077.543021128116, 5157.974210128926, 5169.405518365361, 2564.858932758716, 2569.2934443028316, 2742.969019233758, 2846.7616586784816, 2899.6810350861388, 4901.656884018156, 5781.653100813965, 5872.7357268923, 5884.352721200665, 5910.19265848727, 5522.392536820947, 5577.414371491016, 5602.663840169603, 5756.625819321731, 5792.119280082271, 4833.468318485035, 5172.793088276502, 5356.732163391893, 5666.433356664383, 5732.512736417368, 4847.491013443679, 5643.548516118783, 5666.858328541654, 5676.659400435987, 5684.669285592887, 4823.734694591831, 4909.533594308535, 4983.436842657517, 5606.878624275079, 5653.106209419891, 4768.880777980578, 4871.5128487151005, 5094.7962081516325, 5518.0, 5600.635958662674, 4161.334186530141, 4187.5184353799705, 4389.582989615981, 4584.5186255442695, 4610.2854057621125, 4458.286674132123, 4743.905121897612, 5397.325334333208, 5523.6685798852195, 5843.474087332161, 4762.76186190696, 5360.463953604608, 5422.864428389322, 5483.945160548344, 5548.528375088002, 5487.890242195213, 5488.725563721878, 5492.697901615449, 5500.367457742411, 5571.721413929368, 5007.649464537593, 5032.773525191302, 5069.833938194162, 5298.3906850712665, 5380.139177731544, 4723.834665786819, 4899.289603007473, 5497.505224529811, 5530.55755539564, 5534.418886016911, 4506.977465112785, 4727.787249573847, 5319.813806516753, 5340.2256672783005, 5341.359036915595], "value": 4131.836428351396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.7444907057998, 755.2409828122736, 768.4083255892963, 818.5866137600511, 837.401258100458, 14990.925045374785, 15031.924840375796, 15788.131652759093, 15906.443274485395, 15922.363105475768, 14230.78653820191, 14392.208428536427, 14581.416743330254, 14719.337629806658, 14736.263186840642, 928.9396189247711, 945.1446440970898, 945.7021038372891, 947.4883562876073, 955.3599088610613, 926.3515539122608, 940.3417607674621, 941.7363138321247, 950.1638558068889, 963.6530848894392, 995.8705368302111, 1000.0, 1001.159026417809, 1002.939823610582, 1003.6286573967606, 993.8757655293089, 996.1383403356117, 997.8104160209537, 998.9150922171624, 999.1357475783432, 611.3763960760044, 741.3846507398846, 741.6625435426868, 742.0020073001804, 742.532204711034, 14090.365933532914, 14325.64185895361, 15436.922815385964, 15769.132697701622, 15839.920800395925, 11993.64019079429, 13958.302084895817, 14169.0, 14242.786358204636, 14332.283385830668, 11518.424078796026, 11684.415779211005, 11763.941180294043, 11826.349550774705, 11961.40192990347, 3603.9819800900827, 3668.981655091734, 3702.1114932416294, 3824.0631045394057, 3844.673202777767, 109682.0, 110433.0, 112748.0, 116427.59648219345, 116989.41505292505, 10750.677479675622, 10774.084202842847, 10813.297135686154, 10880.67357979262, 11375.089992800557, 22316.330510084397, 22979.310620681405, 22986.655200172012, 23048.884755576113, 23070.884645576665, 7696.6536505857375, 7720.691172353043, 7786.805329866798, 7850.685972561034, 8034.638441270157, 3776.4335349697767, 4139.606737359946, 4273.18809426208, 4470.508244093147, 4501.662375321864, 3329.933401332008, 3362.747793915466, 3373.1735724747596, 3431.5024321473247, 3435.519027336175, 620.798240571816, 624.4224092714221, 631.1479502671449, 636.4335741190345, 639.1339734659563, 608.3217212807688, 627.4447114303874, 645.3772109913956, 647.9287278399372, 648.8313038609996, 8044.833499142592, 8060.516369017878, 9766.853497197548, 9828.950855245821, 9987.60049598008, 569.8034128330473, 573.9913901291484, 650.7592190889306, 668.879601671694, 673.8113328268092, 4395.208862404734, 4762.095201911626, 4850.7574621269505, 5294.814681486129, 5423.29497165366, 83373.0, 90376.0, 105760.47119764506, 111055.0, 111622.0, 53884.73057634687, 59776.60024379911, 63309.683451582445, 90275.0, 90492.6426103609, 79519.20480795117, 81904.18095819085, 83164.58417707872, 84264.15735842563, 84548.57725711333, 10508.684739457829, 10934.398608076313, 11038.227324087236, 11139.10887129044, 11165.944170279097, 46937.53062469332, 50339.0, 50519.74740126276, 51318.23022654738, 51648.19231326885, 63352.68323658536, 64314.67842660757, 64717.67641161764, 65049.34950650622, 65923.01115483367, 11013.504392302206, 12289.815652765394, 12398.318092504638, 12632.621021369374, 13127.081104322606, 13656.522021729192, 14004.929975350466, 14617.488387906373, 14736.336864840894, 15127.941044127245, 12822.615321540368, 13585.52450664222, 13783.931080344533, 14006.509772157926, 14165.787513187104, 12555.497780088694, 13608.727825443633, 13632.182069075887, 13632.318384080954, 13856.722865542431, 12341.382930853242, 13404.36296538795, 13531.526396576073, 13840.0, 13851.0, 9527.428354298765, 9976.950115249667, 10103.54534045955, 10205.795884082425, 10301.072903438726, 8109.635066421907, 10093.848592271268, 10597.788044239227, 11203.327800332007, 11261.7747645045], "value": 7520.858325713367, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1469.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 156.0, 171.0, 94.0, 168.0, 173.0, 169.0, 116.0, 116.0], "value": 140.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 109.0, 110.0, 140.0, 111.0, 110.0, 109.0, 109.0, 130.0, 110.0], "value": 117.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 263.0, 186.0, 183.0, 186.0, 185.0, 186.0, 183.0, 188.0, 186.0], "value": 195.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 76.0, 83.0, 115.0, 60.0, 63.0, 62.0, 61.0, 75.0, 62.0], "value": 75.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 87.0, 69.0, 69.0, 68.0, 82.0, 67.0, 67.0, 84.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 88.0, 93.0, 88.0, 88.0, 89.0, 88.0, 88.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 94.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 87.0, 87.0, 86.0, 83.0, 91.0, 86.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 64.0, 64.0, 65.0, 63.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 61.0, 62.0, 65.0, 61.0, 58.0, 59.0, 59.0, 58.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 78.0, 95.0, 100.0, 86.0, 81.0, 81.0, 88.0, 88.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 161.0, 128.0, 144.0, 137.0, 132.0, 140.0, 139.0, 175.0, 148.0], "value": 146.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 165.0, 181.0, 218.0, 210.0, 164.0, 166.0, 165.0, 166.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 65.0, 65.0, 64.0, 63.0, 61.0, 63.0, 64.0], "value": 64.4, "unit": "score"}]}]} 09:58:06 INFO - Return code: 0 09:58:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:06 ERROR - # TBPL SUCCESS # 09:58:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:06 INFO - 2017-03-27 09:58:06.362 screenresolution[1658:9904] starting screenresolution argv=screenresolution get 09:58:06 INFO - 2017-03-27 09:58:06.367 screenresolution[1658:9904] Display 0: 1600x1200x32@60 09:58:06 INFO - 2017-03-27 09:58:06.373 screenresolution[1659:9907] starting screenresolution argv=screenresolution list 09:58:06 INFO - Available Modes on Display 0 09:58:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:06 INFO - Intel Iris: 09:58:06 INFO - Chipset Model: Intel Iris 09:58:06 INFO - Type: GPU 09:58:06 INFO - Bus: Built-In 09:58:06 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:06 INFO - Vendor: Intel (0x8086) 09:58:06 INFO - Device ID: 0x0a2e 09:58:06 INFO - Revision ID: 0x0009 09:58:06 INFO - Displays: 09:58:06 INFO - Display: 09:58:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:06 INFO - Display Serial Number: 1600x1200 60 09:58:06 INFO - Main Display: Yes 09:58:06 INFO - Mirror: Off 09:58:06 INFO - Online: Yes 09:58:06 INFO - Rotation: Supported 09:58:06 INFO - Return code: 0 09:58:06 INFO - Running post-action listener: _resource_record_post_action 09:58:06 INFO - [mozharness: 2017-03-27 16:58:06.613115Z] Finished run-tests step (success) 09:58:06 INFO - Running post-run listener: _resource_record_post_run 09:58:06 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 39314432; Write bytes: 510286848; Read time: 383; Write time: 1572 09:58:06 INFO - TinderboxPrint: CPU usage
53.9% 09:58:06 INFO - TinderboxPrint: I/O read bytes / time
39,314,432 / 383 09:58:06 INFO - TinderboxPrint: I/O write bytes / time
510,286,848 / 1,572 09:58:06 INFO - TinderboxPrint: CPU idle
2,217.3 (46.1%) 09:58:06 INFO - TinderboxPrint: CPU system
961.4 (20.0%) 09:58:06 INFO - TinderboxPrint: CPU user
1,627.1 (33.9%) 09:58:06 INFO - TinderboxPrint: Swap in / out
247,107,584 / 0 09:58:06 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203715072; Write bytes: 202012672; Read time: 15734; Write time: 445 09:58:06 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 37250048; Write bytes: 297337856; Read time: 358; Write time: 1096 09:58:07 INFO - Running post-run listener: _upload_blobber_files 09:58:07 INFO - Blob upload gear active. 09:58:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:07 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 09:58:07 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:58:07 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:58:07 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:58:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:58:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:08 INFO - (blobuploader) - INFO - Done attempting. 09:58:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:58:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:58:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:09 INFO - (blobuploader) - INFO - Done attempting. 09:58:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:58:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:58:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:10 INFO - (blobuploader) - INFO - Done attempting. 09:58:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:12 INFO - (blobuploader) - INFO - Done attempting. 09:58:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:12 INFO - Return code: 0 09:58:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df2b3b4a32e6cd32d456009a98a49e4444a429f411eecdae190a5e19f1ddc212a5c6031dbedf1b2eabf74cac42843bafdedafd73333a64b3004807a82f06400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c1f27e9aed00e858f80327742dae0222aabf0dbab8a438813760a73926af1b2a26986cb846592d15520d027be5237c88d3ff4152fd72403fb4fb946794e4aa9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f4e75b22511478ae0044c950ca4fae8a2e7d091c6de001e5b7c73665900c35596cfb7ed45141a049a9f05a64eaec647359e8fd7cc1099298d99400d7320ff0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f492b899c71936d84dd27685b7e2c5b02b860f5e4aa408b28eff4996462b6d193b8084aa55416736ccd1097f31b8323aa76cb3cde46b6ea5a203cbd898d5fd95"} 09:58:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:12 INFO - Contents: 09:58:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df2b3b4a32e6cd32d456009a98a49e4444a429f411eecdae190a5e19f1ddc212a5c6031dbedf1b2eabf74cac42843bafdedafd73333a64b3004807a82f06400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c1f27e9aed00e858f80327742dae0222aabf0dbab8a438813760a73926af1b2a26986cb846592d15520d027be5237c88d3ff4152fd72403fb4fb946794e4aa9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f4e75b22511478ae0044c950ca4fae8a2e7d091c6de001e5b7c73665900c35596cfb7ed45141a049a9f05a64eaec647359e8fd7cc1099298d99400d7320ff0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f492b899c71936d84dd27685b7e2c5b02b860f5e4aa408b28eff4996462b6d193b8084aa55416736ccd1097f31b8323aa76cb3cde46b6ea5a203cbd898d5fd95"} 09:58:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:12 INFO - Copying logs to upload dir... 09:58:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.168311 ========= master_lag: 1.95 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-27 09:58:14.938054) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2017-03-27 09:58:14.945391) ========= 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.DEdimuTPOU/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.8q7YmsYiS4/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df2b3b4a32e6cd32d456009a98a49e4444a429f411eecdae190a5e19f1ddc212a5c6031dbedf1b2eabf74cac42843bafdedafd73333a64b3004807a82f06400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c1f27e9aed00e858f80327742dae0222aabf0dbab8a438813760a73926af1b2a26986cb846592d15520d027be5237c88d3ff4152fd72403fb4fb946794e4aa9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f4e75b22511478ae0044c950ca4fae8a2e7d091c6de001e5b7c73665900c35596cfb7ed45141a049a9f05a64eaec647359e8fd7cc1099298d99400d7320ff0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f492b899c71936d84dd27685b7e2c5b02b860f5e4aa408b28eff4996462b6d193b8084aa55416736ccd1097f31b8323aa76cb3cde46b6ea5a203cbd898d5fd95"} build_url:https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011105 build_url: 'https://queue.taskcluster.net/v1/task/IkVVXxmvTtmaM1_oLasBeg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5df2b3b4a32e6cd32d456009a98a49e4444a429f411eecdae190a5e19f1ddc212a5c6031dbedf1b2eabf74cac42843bafdedafd73333a64b3004807a82f06400", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c1f27e9aed00e858f80327742dae0222aabf0dbab8a438813760a73926af1b2a26986cb846592d15520d027be5237c88d3ff4152fd72403fb4fb946794e4aa9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f4e75b22511478ae0044c950ca4fae8a2e7d091c6de001e5b7c73665900c35596cfb7ed45141a049a9f05a64eaec647359e8fd7cc1099298d99400d7320ff0f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f492b899c71936d84dd27685b7e2c5b02b860f5e4aa408b28eff4996462b6d193b8084aa55416736ccd1097f31b8323aa76cb3cde46b6ea5a203cbd898d5fd95"}' ========= master_lag: 2.13 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2017-03-27 09:58:17.086839) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:58:17.087279) ========= 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.DEdimuTPOU/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.8q7YmsYiS4/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.005692 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 09:58:17.119007) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 09:58:17.119320) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 09:58:17.119688) ========= ========= Total master_lag: 6.07 =========