builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0242 starttime: 1483871751.97 results: success (0) buildid: 20170108004005 builduid: 444daa4f3acd44f295665250c309df75 revision: 4195687bda73679988aaff7b27a8c3b31e38a0fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:51.975080) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:51.975564) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:51.975909) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:52.001884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:52.002189) ========= 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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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-01-08 02:35:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 75.9M=0s 2017-01-08 02:35:52 (75.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152900 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:52.184752) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:52.185738) ========= 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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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.019812 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:52.240078) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-08 02:35:52.240996) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4195687bda73679988aaff7b27a8c3b31e38a0fc --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 4195687bda73679988aaff7b27a8c3b31e38a0fc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ugSFYS29tU/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.yoxVm5LV1Q/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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-01-08 02:35:52,328 truncating revision to first 12 chars 2017-01-08 02:35:52,329 Setting DEBUG logging. 2017-01-08 02:35:52,329 attempt 1/10 2017-01-08 02:35:52,329 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4195687bda73?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-08 02:35:53,754 unpacking tar archive at: mozilla-aurora-4195687bda73/testing/mozharness/ program finished with exit code 0 elapsedTime=1.689778 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-08 02:35:53.959556) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:53.960135) ========= script_repo_revision: 4195687bda73679988aaff7b27a8c3b31e38a0fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:53.960831) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:53.961307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-08 02:35:53.982653) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-08 02:35:53.983133) ========= /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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:35:54 INFO - MultiFileLogger online at 20170108 02:35:54 in /builds/slave/test 02:35:54 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 02:35:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:35:54 INFO - {'append_to_log': False, 02:35:54 INFO - 'base_work_dir': '/builds/slave/test', 02:35:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:35:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:35:54 INFO - 'branch': 'Mozilla-Aurora', 02:35:54 INFO - 'buildbot_json_path': 'buildprops.json', 02:35:54 INFO - 'config_files': ('talos/mac_config.py',), 02:35:54 INFO - 'default_actions': ('clobber', 02:35:54 INFO - 'read-buildbot-config', 02:35:54 INFO - 'download-and-extract', 02:35:54 INFO - 'populate-webroot', 02:35:54 INFO - 'create-virtualenv', 02:35:54 INFO - 'install', 02:35:54 INFO - 'run-tests'), 02:35:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:35:54 INFO - 'download_minidump_stackwalk': True, 02:35:54 INFO - 'download_symbols': 'ondemand', 02:35:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:35:54 INFO - 'tooltool.py': '/tools/tooltool.py', 02:35:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:35:54 INFO - '/tools/misc-python/virtualenv.py')}, 02:35:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:35:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:35:54 INFO - 'installer_path': 'installer.exe', 02:35:54 INFO - 'log_level': 'info', 02:35:54 INFO - 'log_name': 'talos', 02:35:54 INFO - 'log_to_console': True, 02:35:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:35:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:35:54 INFO - 'opt_config_files': (), 02:35:54 INFO - 'pip_index': False, 02:35:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:35:54 INFO - 'cmd': ('bash', 02:35:54 INFO - '-c', 02:35:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:35:54 INFO - 'enabled': True, 02:35:54 INFO - 'halt_on_failure': False, 02:35:54 INFO - 'name': 'check_screen_resolution'},), 02:35:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:35:54 INFO - 'cmd': ('bash', 02:35:54 INFO - '-c', 02:35:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:35:54 INFO - 'enabled': True, 02:35:54 INFO - 'halt_on_failure': False, 02:35:54 INFO - 'name': 'check_screen_resolution'},), 02:35:54 INFO - 'run_cmd_checks_enabled': True, 02:35:54 INFO - 'sps_profile': False, 02:35:54 INFO - 'sps_profile_interval': 0, 02:35:54 INFO - 'suite': 'dromaeojs', 02:35:54 INFO - 'system_bits': '32', 02:35:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:35:54 INFO - 'title': 't-yosemite-r7-0242', 02:35:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:35:54 INFO - 'use_talos_json': True, 02:35:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:35:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:35:54 INFO - 'work_dir': 'build'} 02:35:54 INFO - [mozharness: 2017-01-08 10:35:54.189814Z] Running clobber step. 02:35:54 INFO - Running pre-action listener: _resource_record_pre_action 02:35:54 INFO - Running main action method: clobber 02:35:54 INFO - rmtree: /builds/slave/test/build 02:35:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:35:54 INFO - Running post-action listener: _resource_record_post_action 02:35:54 INFO - [mozharness: 2017-01-08 10:35:54.642176Z] Finished clobber step (success) 02:35:54 INFO - [mozharness: 2017-01-08 10:35:54.642300Z] Running read-buildbot-config step. 02:35:54 INFO - Running pre-action listener: _resource_record_pre_action 02:35:54 INFO - Running main action method: read_buildbot_config 02:35:54 INFO - Using buildbot properties: 02:35:54 INFO - { 02:35:54 INFO - "project": "", 02:35:54 INFO - "product": "firefox", 02:35:54 INFO - "script_repo_revision": "production", 02:35:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:35:54 INFO - "repository": "", 02:35:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:35:54 INFO - "buildid": "20170108004005", 02:35:54 INFO - "stage_platform": "macosx64", 02:35:54 INFO - "basedir": "/builds/slave/test", 02:35:54 INFO - "nightly_build": "True", 02:35:54 INFO - "buildnumber": 20, 02:35:54 INFO - "slavename": "t-yosemite-r7-0242", 02:35:54 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:35:54 INFO - "platform": "yosemite_r7", 02:35:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:35:54 INFO - "branch": "mozilla-aurora", 02:35:54 INFO - "repo_path": "releases/mozilla-aurora", 02:35:54 INFO - "revision": "4195687bda73679988aaff7b27a8c3b31e38a0fc", 02:35:54 INFO - "pgo_build": "False", 02:35:54 INFO - "builduid": "444daa4f3acd44f295665250c309df75", 02:35:54 INFO - "slavebuilddir": "test" 02:35:54 INFO - } 02:35:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:35:54 INFO - Running post-action listener: _resource_record_post_action 02:35:54 INFO - [mozharness: 2017-01-08 10:35:54.645663Z] Finished read-buildbot-config step (success) 02:35:54 INFO - [mozharness: 2017-01-08 10:35:54.645764Z] Running download-and-extract step. 02:35:54 INFO - Running pre-action listener: _resource_record_pre_action 02:35:54 INFO - Running main action method: download_and_extract 02:35:54 INFO - mkdir: /builds/slave/test/build/tests 02:35:54 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')]} 02:35:54 INFO - trying https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:35:54 INFO - Downloading https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:35:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:35:55 INFO - Downloaded 1199 bytes. 02:35:55 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:35:55 INFO - Using the following test package requirements: 02:35:55 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:35:55 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:55 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:35:55 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:55 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:35:55 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:55 INFO - u'jsshell-mac.zip'], 02:35:55 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:55 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:35:55 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:35:55 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:55 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:35:55 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:55 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:35:55 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:55 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:35:55 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:35:55 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:35:55 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:35:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:35:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:35:55 INFO - Fetch https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:35:55 INFO - Http code: 200 02:35:55 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 02:35:55 INFO - x-amz-cf-id: 3d5MryTQWnPBS-Xe0gBAYrWzXIKhAkCPhHxKOX7WZT8MippIwqwvLQ== 02:35:55 INFO - x-amz-version-id: pAZ44Ngzs71LNCIGGrPsUKguk3dze3xi 02:35:56 INFO - Expected file size: 16270967 02:35:56 INFO - Obtained file size: 16270967 02:36:02 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:36:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:36:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:36:02 INFO - Fetch https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:36:03 INFO - Http code: 200 02:36:03 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 02:36:03 INFO - x-amz-cf-id: lsSpL4qisOpHct_GudemLv5iJwnzHKohlLMT85ddQsS65mx3yEunwQ== 02:36:03 INFO - x-amz-version-id: pAZ44Ngzs71LNCIGGrPsUKguk3dze3xi 02:36:03 INFO - Expected file size: 16270967 02:36:03 INFO - Obtained file size: 16270967 02:36:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:36:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:36:09 INFO - Fetch https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:36:11 INFO - Http code: 200 02:36:11 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 02:36:11 INFO - x-amz-cf-id: T9RYj9dhFLv9MBMlWl6t1kNyBmsftQ8dHOM9gncF7ucbU95o3OfobQ== 02:36:11 INFO - x-amz-version-id: HT.wyrkeqXe0f4bVVJBe9.0ezEYqB_3d 02:36:11 INFO - Expected file size: 14409038 02:36:11 INFO - Obtained file size: 14409038 02:36:12 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')]} 02:36:12 INFO - trying https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:36:12 INFO - Downloading https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:36:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:36:15 INFO - Downloaded 110001326 bytes. 02:36:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:36:15 INFO - mkdir: /builds/slave/test/properties 02:36:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:36:15 INFO - Writing to file /builds/slave/test/properties/build_url 02:36:15 INFO - Contents: 02:36:15 INFO - build_url:https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:36:16 INFO - Running post-action listener: _resource_record_post_action 02:36:16 INFO - Running post-action listener: set_extra_try_arguments 02:36:16 INFO - [mozharness: 2017-01-08 10:36:16.175894Z] Finished download-and-extract step (success) 02:36:16 INFO - [mozharness: 2017-01-08 10:36:16.176056Z] Running populate-webroot step. 02:36:16 INFO - Running pre-action listener: _resource_record_pre_action 02:36:16 INFO - Running main action method: populate_webroot 02:36:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:36:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:36:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:36:16 INFO - '--tppagecycles', 02:36:16 INFO - '7'], 02:36:16 INFO - 'tests': ['tsvgm']}}, 02:36:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:36:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:36:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:36:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:36:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:16 INFO - 'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:36:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:36:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:16 INFO - 'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['damp', 'tps']}, 02:36:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:16 INFO - 'tests': ['damp', 'tps']}, 02:36:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['dromaeo_dom']}, 02:36:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:36:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['basic_compositor_video']}, 02:36:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:36:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['a11yr', 02:36:16 INFO - 'ts_paint', 02:36:16 INFO - 'tpaint', 02:36:16 INFO - 'sessionrestore', 02:36:16 INFO - 'sessionrestore_no_auto_restore', 02:36:16 INFO - 'tabpaint']}, 02:36:16 INFO - 'other-e10s': {'tests': ['a11yr', 02:36:16 INFO - 'ts_paint', 02:36:16 INFO - 'tpaint', 02:36:16 INFO - 'sessionrestore', 02:36:16 INFO - 'sessionrestore_no_auto_restore', 02:36:16 INFO - 'tabpaint']}, 02:36:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:36:16 INFO - 'ts_paint', 02:36:16 INFO - 'tpaint', 02:36:16 INFO - 'sessionrestore', 02:36:16 INFO - 'sessionrestore_no_auto_restore', 02:36:16 INFO - 'tabpaint']}, 02:36:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:36:16 INFO - 'ts_paint', 02:36:16 INFO - 'tpaint', 02:36:16 INFO - 'sessionrestore', 02:36:16 INFO - 'sessionrestore_no_auto_restore', 02:36:16 INFO - 'tabpaint']}, 02:36:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['a11yr', 02:36:16 INFO - 'ts_paint', 02:36:16 INFO - 'tpaint', 02:36:16 INFO - 'sessionrestore', 02:36:16 INFO - 'sessionrestore_no_auto_restore', 02:36:16 INFO - 'tabpaint']}, 02:36:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['a11yr', 02:36:16 INFO - 'ts_paint', 02:36:16 INFO - 'tpaint', 02:36:16 INFO - 'sessionrestore', 02:36:16 INFO - 'sessionrestore_no_auto_restore', 02:36:16 INFO - 'tabpaint']}, 02:36:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['tsvgx', 02:36:16 INFO - 'tsvgr_opacity', 02:36:16 INFO - 'tart', 02:36:16 INFO - 'tscrollx', 02:36:16 INFO - 'cart']}, 02:36:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:36:16 INFO - 'tsvgr_opacity', 02:36:16 INFO - 'tart', 02:36:16 INFO - 'tscrollx', 02:36:16 INFO - 'cart']}, 02:36:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:16 INFO - 'talos_options': ['--disable-e10s'], 02:36:16 INFO - 'tests': ['tp5o']}, 02:36:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:16 INFO - 'tests': ['tp5o']}, 02:36:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:16 INFO - 'talos_options': ['--disable-e10s', 02:36:16 INFO - '--xperf_path', 02:36:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:36:16 INFO - 'tests': ['tp5n']}, 02:36:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:36:16 INFO - 'talos_options': ['--xperf_path', 02:36:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:36:16 INFO - 'tests': ['tp5n']}}, 02:36:16 INFO - 'talos.zip': {'path': '', 02:36:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:36:16 INFO - Running post-action listener: _resource_record_post_action 02:36:16 INFO - [mozharness: 2017-01-08 10:36:16.194961Z] Finished populate-webroot step (success) 02:36:16 INFO - [mozharness: 2017-01-08 10:36:16.195090Z] Running create-virtualenv step. 02:36:16 INFO - Running pre-action listener: _resource_record_pre_action 02:36:16 INFO - Running main action method: create_virtualenv 02:36:16 INFO - Creating virtualenv /builds/slave/test/build/venv 02:36:16 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 02:36:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:36:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:36:16 INFO - Using real prefix '/tools/python27' 02:36:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:36:17 INFO - Installing distribute.............................................................................................................................................................................................done. 02:36:19 INFO - Installing pip.................done. 02:36:19 INFO - Return code: 0 02:36:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:36:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:36:20 INFO - Reading from file tmpfile_stdout 02:36:20 INFO - Output received: 02:36:20 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) 02:36:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:36:20 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')]} 02:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:20 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')]} 02:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:20 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 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36:20 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 02:36:20 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 02:36:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:20 INFO - 'HOME': '/Users/cltbld', 02:36:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:20 INFO - 'LOGNAME': 'cltbld', 02:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:20 INFO - 'MOZ_NO_REMOTE': '1', 02:36:20 INFO - 'NO_EM_RESTART': '1', 02:36:20 INFO - 'PAGER': '/bin/cat', 02:36:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:20 INFO - 'PWD': '/builds/slave/test', 02:36:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:20 INFO - 'SHELL': '/bin/bash', 02:36:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:20 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:20 INFO - 'USER': 'cltbld', 02:36:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:20 INFO - 'XPC_FLAGS': '0x0', 02:36:20 INFO - 'XPC_SERVICE_NAME': '0', 02:36:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:20 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 02:36:20 INFO - Cleaning up... 02:36:20 INFO - Return code: 0 02:36:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:36:20 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')]} 02:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:20 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')]} 02:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:20 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 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36:20 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 02:36:20 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 02:36:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:20 INFO - 'HOME': '/Users/cltbld', 02:36:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:20 INFO - 'LOGNAME': 'cltbld', 02:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:20 INFO - 'MOZ_NO_REMOTE': '1', 02:36:20 INFO - 'NO_EM_RESTART': '1', 02:36:20 INFO - 'PAGER': '/bin/cat', 02:36:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:20 INFO - 'PWD': '/builds/slave/test', 02:36:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:20 INFO - 'SHELL': '/bin/bash', 02:36:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:20 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:20 INFO - 'USER': 'cltbld', 02:36:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:20 INFO - 'XPC_FLAGS': '0x0', 02:36:20 INFO - 'XPC_SERVICE_NAME': '0', 02:36:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:20 INFO - Downloading/unpacking psutil>=3.1.1 02:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:20 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 02:36:20 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 02:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:36:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:36:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:36:24 INFO - Installing collected packages: psutil 02:36:24 INFO - Running setup.py install for psutil 02:36:24 INFO - building 'psutil._psutil_osx' extension 02:36:24 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 02:36:25 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 02:36:25 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 02:36:25 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 02:36:25 INFO - building 'psutil._psutil_posix' extension 02:36:25 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 02:36:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:36:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:36:25 INFO - ^ 02:36:25 INFO - 1 warning generated. 02:36:25 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 02:36:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:36:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:36:25 INFO - Successfully installed psutil 02:36:25 INFO - Cleaning up... 02:36:25 INFO - Return code: 0 02:36:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:36: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')]} 02:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36: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')]} 02:36:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:25 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 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36:25 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 02:36:25 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 02:36:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:25 INFO - 'HOME': '/Users/cltbld', 02:36:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:25 INFO - 'LOGNAME': 'cltbld', 02:36:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:25 INFO - 'MOZ_NO_REMOTE': '1', 02:36:25 INFO - 'NO_EM_RESTART': '1', 02:36:25 INFO - 'PAGER': '/bin/cat', 02:36:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:25 INFO - 'PWD': '/builds/slave/test', 02:36:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:25 INFO - 'SHELL': '/bin/bash', 02:36:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:25 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:25 INFO - 'USER': 'cltbld', 02:36:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:25 INFO - 'XPC_FLAGS': '0x0', 02:36:25 INFO - 'XPC_SERVICE_NAME': '0', 02:36:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:36:25 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 02:36:25 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 02:36:25 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 02:36:25 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 02:36:25 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 02:36:25 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 02:36:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:36:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:36:28 INFO - Installing collected packages: mozsystemmonitor 02:36:28 INFO - Running setup.py install for mozsystemmonitor 02:36:28 INFO - Successfully installed mozsystemmonitor 02:36:28 INFO - Cleaning up... 02:36:28 INFO - Return code: 0 02:36:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:36: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')]} 02:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36: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')]} 02:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:28 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 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36:28 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 02:36:28 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 02:36:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:28 INFO - 'HOME': '/Users/cltbld', 02:36:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:28 INFO - 'LOGNAME': 'cltbld', 02:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:28 INFO - 'MOZ_NO_REMOTE': '1', 02:36:28 INFO - 'NO_EM_RESTART': '1', 02:36:28 INFO - 'PAGER': '/bin/cat', 02:36:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:28 INFO - 'PWD': '/builds/slave/test', 02:36:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:28 INFO - 'SHELL': '/bin/bash', 02:36:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:28 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:28 INFO - 'USER': 'cltbld', 02:36:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:28 INFO - 'XPC_FLAGS': '0x0', 02:36:28 INFO - 'XPC_SERVICE_NAME': '0', 02:36:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:29 INFO - Downloading/unpacking jsonschema==2.5.1 02:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:36:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:36:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:36:31 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 02:36:31 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 02:36:31 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 02:36:31 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 02:36:31 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 02:36:31 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 02:36:32 INFO - Downloading functools32-3.2.3-2.tar.gz 02:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:36:32 INFO - warning: no files found matching '*.txt' 02:36:32 INFO - no previously-included directories found matching 'build' 02:36:32 INFO - no previously-included directories found matching 'dist' 02:36:32 INFO - no previously-included directories found matching '.git*' 02:36:32 INFO - Installing collected packages: jsonschema, functools32 02:36:32 INFO - Running setup.py install for functools32 02:36:32 INFO - warning: no files found matching '*.txt' 02:36:32 INFO - no previously-included directories found matching 'build' 02:36:32 INFO - no previously-included directories found matching 'dist' 02:36:32 INFO - no previously-included directories found matching '.git*' 02:36:32 INFO - Successfully installed jsonschema functools32 02:36:32 INFO - Cleaning up... 02:36:32 INFO - Return code: 0 02:36:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:36:32 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')]} 02:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:32 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')]} 02:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:32 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 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36:32 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 02:36:32 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 02:36:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:32 INFO - 'HOME': '/Users/cltbld', 02:36:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:32 INFO - 'LOGNAME': 'cltbld', 02:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:32 INFO - 'MOZ_NO_REMOTE': '1', 02:36:32 INFO - 'NO_EM_RESTART': '1', 02:36:32 INFO - 'PAGER': '/bin/cat', 02:36:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:32 INFO - 'PWD': '/builds/slave/test', 02:36:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:32 INFO - 'SHELL': '/bin/bash', 02:36:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:32 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:32 INFO - 'USER': 'cltbld', 02:36:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:32 INFO - 'XPC_FLAGS': '0x0', 02:36:32 INFO - 'XPC_SERVICE_NAME': '0', 02:36:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:36:32 INFO - Cleaning up... 02:36:32 INFO - Return code: 0 02:36:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:36:32 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')]} 02:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:32 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')]} 02:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:32 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 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36:32 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 02:36:32 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 02:36:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:32 INFO - 'HOME': '/Users/cltbld', 02:36:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:32 INFO - 'LOGNAME': 'cltbld', 02:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:32 INFO - 'MOZ_NO_REMOTE': '1', 02:36:32 INFO - 'NO_EM_RESTART': '1', 02:36:32 INFO - 'PAGER': '/bin/cat', 02:36:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:32 INFO - 'PWD': '/builds/slave/test', 02:36:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:32 INFO - 'SHELL': '/bin/bash', 02:36:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:32 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:32 INFO - 'USER': 'cltbld', 02:36:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:32 INFO - 'XPC_FLAGS': '0x0', 02:36:32 INFO - 'XPC_SERVICE_NAME': '0', 02:36:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:32 INFO - Downloading/unpacking blobuploader==1.2.4 02:36:32 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 02:36:32 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 02:36:32 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 02:36:32 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 02:36:32 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 02:36:32 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 02:36:35 INFO - Downloading blobuploader-1.2.4.tar.gz 02:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:36:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:36: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 02:36: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 02:36: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 02:36: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 02:36: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 02:36: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 02:36:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:36:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:36:36 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 02:36:36 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 02:36:36 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 02:36:36 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 02:36:36 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 02:36:36 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 02:36:36 INFO - Downloading docopt-0.6.1.tar.gz 02:36:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:36:36 INFO - Installing collected packages: blobuploader, requests, docopt 02:36:36 INFO - Running setup.py install for blobuploader 02:36:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:36:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:36:37 INFO - Running setup.py install for requests 02:36:37 INFO - Running setup.py install for docopt 02:36:37 INFO - Successfully installed blobuploader requests docopt 02:36:37 INFO - Cleaning up... 02:36:37 INFO - Return code: 0 02:36:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:36:37 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')]} 02:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:37 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')]} 02:36:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:37 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 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36:37 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 02:36:37 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 02:36:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:37 INFO - 'HOME': '/Users/cltbld', 02:36:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:37 INFO - 'LOGNAME': 'cltbld', 02:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:37 INFO - 'MOZ_NO_REMOTE': '1', 02:36:37 INFO - 'NO_EM_RESTART': '1', 02:36:37 INFO - 'PAGER': '/bin/cat', 02:36:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:37 INFO - 'PWD': '/builds/slave/test', 02:36:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:37 INFO - 'SHELL': '/bin/bash', 02:36:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:37 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:37 INFO - 'USER': 'cltbld', 02:36:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:37 INFO - 'XPC_FLAGS': '0x0', 02:36:37 INFO - 'XPC_SERVICE_NAME': '0', 02:36:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-GpFxK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-_w3Jky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-9gklIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-5EqMiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-NokWoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-BZefqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-g_xm7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-6wEpX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-iE7cA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:36:38 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-R4S8Kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:36:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-Gj9jDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:36:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-NcEmTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:36:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-3fmm6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:36:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-5iMa9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-m63phG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:36:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-ac5Hkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:36:39 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-jRfI6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:36:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:36:39 INFO - Running setup.py install for manifestparser 02:36:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:36:39 INFO - Running setup.py install for mozcrash 02:36:40 INFO - Running setup.py install for mozdebug 02:36:40 INFO - Running setup.py install for mozdevice 02:36:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:36:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:36:40 INFO - Running setup.py install for mozfile 02:36:40 INFO - Running setup.py install for mozhttpd 02:36:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:36:40 INFO - Running setup.py install for mozinfo 02:36:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:36:40 INFO - Running setup.py install for mozInstall 02:36:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:36:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:36:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:36:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:36:40 INFO - Running setup.py install for mozleak 02:36:41 INFO - Running setup.py install for mozlog 02:36:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:36:41 INFO - Running setup.py install for moznetwork 02:36:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:36:41 INFO - Running setup.py install for mozprocess 02:36:41 INFO - Running setup.py install for mozprofile 02:36:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:36:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:36:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:36:41 INFO - Running setup.py install for mozrunner 02:36:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:36:41 INFO - Running setup.py install for mozscreenshot 02:36:42 INFO - Running setup.py install for moztest 02:36:42 INFO - Running setup.py install for mozversion 02:36:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:36:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:36:42 INFO - Cleaning up... 02:36:42 INFO - Return code: 0 02:36:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:36:42 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')]} 02:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:42 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')]} 02:36:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:42 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 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36:42 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 02:36:42 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 02:36:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:42 INFO - 'HOME': '/Users/cltbld', 02:36:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:42 INFO - 'LOGNAME': 'cltbld', 02:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:42 INFO - 'MOZ_NO_REMOTE': '1', 02:36:42 INFO - 'NO_EM_RESTART': '1', 02:36:42 INFO - 'PAGER': '/bin/cat', 02:36:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:42 INFO - 'PWD': '/builds/slave/test', 02:36:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:42 INFO - 'SHELL': '/bin/bash', 02:36:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:42 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:42 INFO - 'USER': 'cltbld', 02:36:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:42 INFO - 'XPC_FLAGS': '0x0', 02:36:42 INFO - 'XPC_SERVICE_NAME': '0', 02:36:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:36:42 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-foYbjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:36:42 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)) 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:36:42 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-NmdEZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:36:42 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)) 02:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:36:42 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-vgg2rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:36:43 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)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-om7FUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-BkzBR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:36:43 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)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-lbtME2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:36:43 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)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-h3yM_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:36:43 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)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-MCeh6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:36:43 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)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-u68WIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:36:43 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)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-tj6moX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:36:43 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)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-gchcP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:36:43 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)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-4lf5sE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:36:43 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-rJ6IGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:36:44 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)) 02:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:36:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-IQuxLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:36:44 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)) 02:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-EtWoxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:36:44 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)) 02:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:36:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-6sosO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:36:44 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)) 02:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:36:44 INFO - Running setup.py (path:/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/pip-YGWxwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:36:44 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)) 02:36:44 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)) 02:36:44 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)) 02:36:44 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)) 02:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:36:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:36: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 02:36: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 02:36: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 02:36: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 02:36: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 02:36: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 02:36:47 INFO - Downloading blessings-1.6.tar.gz 02:36:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:36:47 INFO - Installing collected packages: blessings 02:36:47 INFO - Running setup.py install for blessings 02:36:47 INFO - Successfully installed blessings 02:36:47 INFO - Cleaning up... 02:36:47 INFO - Return code: 0 02:36:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:36:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:36:47 INFO - Reading from file tmpfile_stdout 02:36:47 INFO - Current package versions: 02:36:47 INFO - blessings == 1.6 02:36:47 INFO - blobuploader == 1.2.4 02:36:47 INFO - docopt == 0.6.1 02:36:47 INFO - functools32 == 3.2.3-2 02:36:47 INFO - jsonschema == 2.5.1 02:36:47 INFO - manifestparser == 1.1 02:36:47 INFO - mozInstall == 1.12 02:36:47 INFO - mozcrash == 1.0 02:36:47 INFO - mozdebug == 0.1 02:36:47 INFO - mozdevice == 0.48 02:36:47 INFO - mozfile == 1.2 02:36:47 INFO - mozhttpd == 0.7 02:36:47 INFO - mozinfo == 0.9 02:36:47 INFO - mozleak == 0.1 02:36:47 INFO - mozlog == 3.4 02:36:47 INFO - moznetwork == 0.27 02:36:47 INFO - mozprocess == 0.23 02:36:47 INFO - mozprofile == 0.28 02:36:47 INFO - mozrunner == 6.13 02:36:47 INFO - mozscreenshot == 0.1 02:36:47 INFO - mozsystemmonitor == 0.3 02:36:47 INFO - moztest == 0.8 02:36:47 INFO - mozversion == 1.4 02:36:47 INFO - psutil == 3.1.1 02:36:47 INFO - requests == 1.2.3 02:36:47 INFO - wsgiref == 0.1.2 02:36:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:36:47 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')]} 02:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:47 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')]} 02:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36:47 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 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36:47 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 02:36:47 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 02:36:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:47 INFO - 'HOME': '/Users/cltbld', 02:36:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:47 INFO - 'LOGNAME': 'cltbld', 02:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:47 INFO - 'MOZ_NO_REMOTE': '1', 02:36:47 INFO - 'NO_EM_RESTART': '1', 02:36:47 INFO - 'PAGER': '/bin/cat', 02:36:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:47 INFO - 'PWD': '/builds/slave/test', 02:36:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:47 INFO - 'SHELL': '/bin/bash', 02:36:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:47 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:47 INFO - 'USER': 'cltbld', 02:36:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:47 INFO - 'XPC_FLAGS': '0x0', 02:36:47 INFO - 'XPC_SERVICE_NAME': '0', 02:36:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:48 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)) 02:36:48 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)) 02:36:48 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)) 02:36:48 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)) 02:36:48 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)) 02:36:48 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)) 02:36:48 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)) 02:36:48 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)) 02:36:48 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)) 02:36:48 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)) 02:36:48 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)) 02:36:48 INFO - Cleaning up... 02:36:48 INFO - Return code: 0 02:36:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:36: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')]} 02:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:36:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36: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')]} 02:36:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:36:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:36: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e480490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80f35e0e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, '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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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 02:36: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', 'jsonschema'] in /builds/slave/test/build 02:36: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 jsonschema 02:36:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:36:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:36:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:36:48 INFO - 'HOME': '/Users/cltbld', 02:36:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:36:48 INFO - 'LOGNAME': 'cltbld', 02:36:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:36:48 INFO - 'MOZ_NO_REMOTE': '1', 02:36:48 INFO - 'NO_EM_RESTART': '1', 02:36:48 INFO - 'PAGER': '/bin/cat', 02:36:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:36:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:36:48 INFO - 'PWD': '/builds/slave/test', 02:36:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:36:48 INFO - 'SHELL': '/bin/bash', 02:36:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:36:48 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:36:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:36:48 INFO - 'USER': 'cltbld', 02:36:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:36:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:36:48 INFO - 'XPC_FLAGS': '0x0', 02:36:48 INFO - 'XPC_SERVICE_NAME': '0', 02:36:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:36:48 INFO - Cleaning up... 02:36:48 INFO - Return code: 0 02:36:48 INFO - Running post-action listener: _resource_record_post_action 02:36:48 INFO - Running post-action listener: _start_resource_monitoring 02:36:48 INFO - Starting resource monitoring. 02:36:48 INFO - [mozharness: 2017-01-08 10:36:48.387888Z] Finished create-virtualenv step (success) 02:36:48 INFO - [mozharness: 2017-01-08 10:36:48.388959Z] Running install step. 02:36:48 INFO - Running pre-action listener: _resource_record_pre_action 02:36:48 INFO - Running main action method: install 02:36:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:36:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:36:48 INFO - Reading from file tmpfile_stdout 02:36:48 INFO - Detecting whether we're running mozinstall >=1.0... 02:36:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:36:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:36:48 INFO - Reading from file tmpfile_stdout 02:36:48 INFO - Output received: 02:36:48 INFO - Usage: mozinstall [options] installer 02:36:48 INFO - Options: 02:36:48 INFO - -h, --help show this help message and exit 02:36:48 INFO - -d DEST, --destination=DEST 02:36:48 INFO - Directory to install application into. [default: 02:36:48 INFO - "/builds/slave/test"] 02:36:48 INFO - --app=APP Application being installed. [default: firefox] 02:36:48 INFO - mkdir: /builds/slave/test/build/application 02:36:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:36:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:37:19 INFO - Reading from file tmpfile_stdout 02:37:19 INFO - Output received: 02:37:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:37:19 INFO - Running post-action listener: _resource_record_post_action 02:37:19 INFO - [mozharness: 2017-01-08 10:37:19.984799Z] Finished install step (success) 02:37:19 INFO - [mozharness: 2017-01-08 10:37:19.984927Z] Running run-tests step. 02:37:19 INFO - Running pre-action listener: _resource_record_pre_action 02:37:19 INFO - Running main action method: run_tests 02:37:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:37:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:37:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:37:20 INFO - 2017-01-08 02:37:20.000 screenresolution[2282:24517] starting screenresolution argv=screenresolution get 02:37:20 INFO - 2017-01-08 02:37:20.002 screenresolution[2282:24517] Display 0: 1600x1200x32@60 02:37:20 INFO - 2017-01-08 02:37:20.008 screenresolution[2283:24519] starting screenresolution argv=screenresolution list 02:37:20 INFO - Available Modes on Display 0 02:37:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:37:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:37:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:37:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:37:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:37:20 INFO - Intel Iris: 02:37:20 INFO - Chipset Model: Intel Iris 02:37:20 INFO - Type: GPU 02:37:20 INFO - Bus: Built-In 02:37:20 INFO - VRAM (Dynamic, Max): 1536 MB 02:37:20 INFO - Vendor: Intel (0x8086) 02:37:20 INFO - Device ID: 0x0a2e 02:37:20 INFO - Revision ID: 0x0009 02:37:20 INFO - Displays: 02:37:20 INFO - Display: 02:37:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:37:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:37:20 INFO - Display Serial Number: 1600x1200 60 02:37:20 INFO - Main Display: Yes 02:37:20 INFO - Mirror: Off 02:37:20 INFO - Online: Yes 02:37:20 INFO - Rotation: Supported 02:37:20 INFO - Return code: 0 02:37:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:37:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:37:20 INFO - Python 2.7.3 02:37:20 INFO - Return code: 0 02:37:20 INFO - grabbing minidump binary from tooltool 02:37:20 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')]} 02:37:20 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 0x10e294d30>, '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 0x10e0fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e28cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:37:20 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 02:37:20 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 02:37:20 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 02:37:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:37:20 INFO - Return code: 0 02:37:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:37:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:37:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:37:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:37:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:37:20 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/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0242', '--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 02:37:20 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/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0242 --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 02:37:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ugSFYS29tU/Render', 02:37:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:20 INFO - 'HOME': '/Users/cltbld', 02:37:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:20 INFO - 'LOGNAME': 'cltbld', 02:37:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:37:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:20 INFO - 'MOZ_NO_REMOTE': '1', 02:37:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:37:20 INFO - 'NO_EM_RESTART': '1', 02:37:20 INFO - 'PAGER': '/bin/cat', 02:37:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:20 INFO - 'PWD': '/builds/slave/test', 02:37:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:37:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:20 INFO - 'SHELL': '/bin/bash', 02:37:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yoxVm5LV1Q/Listeners', 02:37:20 INFO - 'TMPDIR': '/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/', 02:37:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:20 INFO - 'USER': 'cltbld', 02:37:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:20 INFO - 'XPC_FLAGS': '0x0', 02:37:20 INFO - 'XPC_SERVICE_NAME': '0', 02:37:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:20 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/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0242', '--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 02:37:20 INFO - mozversion application_buildid: 20170108004005 02:37:20 INFO - mozversion application_changeset: 4195687bda73679988aaff7b27a8c3b31e38a0fc 02:37:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:37:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:37:20 INFO - mozversion application_name: Firefox 02:37:20 INFO - mozversion application_remotingname: firefox-dev 02:37:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:37:20 INFO - mozversion application_vendor: Mozilla 02:37:20 INFO - mozversion application_version: 52.0a2 02:37:20 INFO - mozversion platform_buildid: 20170108004005 02:37:20 INFO - mozversion platform_changeset: 4195687bda73679988aaff7b27a8c3b31e38a0fc 02:37:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:37:20 INFO - mozversion platform_version: 52.0a2 02:37:20 INFO - using testdate: 1483871840 02:37:20 INFO - actual date: 1483871840 02:37:20 INFO - starting webserver on 'localhost:50044' 02:37:20 INFO - SUITE-START | Running 2 tests 02:37:20 INFO - TEST-START | dromaeo_css 02:37:20 INFO - operating with platform_type : mac_ 02:37:20 INFO - Initialising browser for dromaeo_css test... 02:37:20 INFO - TEST-INFO | started process 2293 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpwfz2Wj/profile http://localhost:50044/getInfo.html) 02:37:23 INFO - PROCESS | 2293 | __metrics Screen width/height:1600/1200 02:37:23 INFO - PROCESS | 2293 | colorDepth:24 02:37:23 INFO - PROCESS | 2293 | Browser inner width/height: 1024/689 02:37:23 INFO - PROCESS | 2293 | __metrics 02:37:23 INFO - PROCESS | 2293 | 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" 02:37:23 INFO - PROCESS | 2293 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:37:24 INFO - TEST-INFO | 2293: exit 0 02:37:24 INFO - Browser initialized. 02:37:24 INFO - Running cycle 1/1 for dromaeo_css test... 02:37:24 INFO - TEST-INFO | started process 2296 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpwfz2Wj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:37:36 INFO - PROCESS | 2296 | RSS: Main: 223330304 02:37:36 INFO - PROCESS | 2296 | 02:40:40 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:50044/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50044/tests/dromaeo/cssquery-ext.html) 02:40:43 INFO - PROCESS | 2296 | RSS: Main: 429330432 02:40:43 INFO - PROCESS | 2296 | 02:43:46 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:50044/tests/dromaeo/cssquery-ext.html (next: http://localhost:50044/tests/dromaeo/cssquery-jquery.html) 02:43:46 INFO - PROCESS | 2296 | RSS: Main: 348205056 02:43:46 INFO - PROCESS | 2296 | 02:46:49 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:50044/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50044/tests/dromaeo/cssquery-mootools.html) 02:46:49 INFO - PROCESS | 2296 | RSS: Main: 334934016 02:46:49 INFO - PROCESS | 2296 | 02:49:52 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:50044/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50044/tests/dromaeo/cssquery-prototype.html) 02:49:52 INFO - PROCESS | 2296 | RSS: Main: 322220032 02:49:52 INFO - PROCESS | 2296 | 02:52:56 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:50044/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50044/tests/dromaeo/cssquery-yui.html) 02:52:56 INFO - PROCESS | 2296 | RSS: Main: 315944960 02:52:56 INFO - PROCESS | 2296 | 02:55:59 INFO - PROCESS | 2296 | Cycle 1(1): loaded http://localhost:50044/tests/dromaeo/cssquery-yui.html (next: http://localhost:50044/tests/dromaeo/cssquery-dojo.html) 02:56:00 INFO - PROCESS | 2296 | RSS: Main: 492126208 02:56:00 INFO - PROCESS | 2296 | 02:56:00 INFO - PROCESS | 2296 | __start_tp_report 02:56:00 INFO - PROCESS | 2296 | _x_x_mozilla_page_load 02:56:00 INFO - PROCESS | 2296 | _x_x_mozilla_page_load_details 02:56:00 INFO - PROCESS | 2296 | |i|pagename|runs| 02:56:00 INFO - PROCESS | 2296 | |0;dojo.html;3200.9039728808116;3214.887478938236;3215.356928614278;3236.983815080924;3265.167382317509;5464.98897703925;5477.178423236516;5531.3915469298345;5534.584906132041;5547;5453.7099013956495;5513.365962914266;5545.306836645419;5553.805616803413;5603.467670571299;5007.123753343169;5015.673981191211;5043.747812609373;5129.28190053391;5134.589232861381;5013.699178049311;5016.69899806011;5038.84883453497;5039.84880453587;5121.641485096045;5029.597632189434;5070.645054846163;5082.720450375228;5093.465186155438;5096.286519887219;5059.443461219282;5102.846914592568;5125.718085505296;5133.768980395891;5174.844754657366;4818.4217893852965;5046.394432668067;5059.342285502861;5080.415752188509;5090.236464530313;5338.386085600128;5381.192821076831;5405.837824865221;5422.40353561108;5427.864303392407;5983.491403230731;6000.609960352606;6045.123457098696;6071.574989750719;6096.963516202212;6148.38516148386;6152.261728592597;6159.846003849895;6162.815115546541;6168.96915515424;5524.364698059734;5573.916391254134;5582.91625625616;5585.301837270301;5632.859178520488;5166.767495462713;6192.8761424771255;6228.34602366745;6242.15730876335;6261.8747625047245;5877.324107727581;5878.5297176225795;5878.588498805043;5887.970560147258;5893.381194974551;5812.0119579671555;5856.238688970492;5860.970695146497;5874.9118763218585;6065.787697430569;5831.329397119301;5831.708414579254;5833.562482813806;5833.766649334064;5836.182934389104;5785.971070144707;5874.500667443315;5875.177475153482;5880.794172203952;5890.028145356054;4733.219018861918;4742.9762851186215;4750.7387093709485;4754.738489383048;4758.214894542361;4277.893052673708;4290.377895205178;4303.225419424533;4309.288967320357;4322.891927701832;5514;5538.446155384429;5540.889182216333;5542.972285138549;5543.584231182677;6043.607165534227;6055.2430946131735;6058.363871793486;6063.575549711478;6074.483668888106;5542.196381524656;5559.944400555943;5565.972170139124;5573.27547418833;5591.804286850022;5157.123289040872;5198.948010519922;5243.056249875059;5249.895002100013;5268.894622107537;6167.414095660905;6191.628502289877;6231.034189700604;6233.189685340877;6262.060690896402;6080.847978800565;6106.725197366218;6114.7248373822395;6125.326214116532;6146.661933593517;4628.60656844172;5018.44797072329;5225.529702326809;6273.65494897785;6298.653574053471;5744.511716504144;5769.249997500466;5836.9416305838095;5894.734736936763;5951.91072133927;6007.098935159761;6043.093535969464;6047.606905551125;6053.939460605337;6063.423974722395;5826.388229235869;5861;5862.853428664317;5864;5879.118132280192;5923.437273459014;5966.105084237399;5966.791162309472;5967.1944287522365;5977.193078934288;5761.711914404347;5783.334916484658;5799.797007104731;5802.883942321215;5815.476607105212;5801.448862358069;5814.244148260699;5864.091065884794;5936.703164841654;5950.256217972754;5742.827715168552;5762.337331206797;5806.071028635398;5817.76728930838;5822.301323841166 02:56:00 INFO - PROCESS | 2296 | |1;ext.html;444.6198500282258;472.30335255498125;480.52668121899944;490.2156549520767;498.86780003299134;35958.82020589897;37296.813515932416;38812.80593597032;38853.805730971304;39132.60867391325;39709.205815883666;40115.19769604606;40131.79934100326;40216.396754048714;40234;9176.58705358257;9219.95390023049;9258.21305189058;9261.305402094837;9425.19885809707;9390.530473476332;9503.762405939873;9510.66712665057;9510.714678559654;9520.8571871422;9433.009533998935;9509.524523773818;9555.80888382232;9576.994415586367;9607.231421486264;9471.573779179953;9519.428834269896;9541.713748587554;9554.378965367263;9564.617415303379;9330.113639204264;9407.57665905029;9410.341276110676;9415.387999780061;9421.340506164572;37437.62562374368;37899.621003789885;38363;38395;38400.42399364012;2929.956050659242;3134.1067795678123;3231.709146176832;3443.8794642187404;3809.5428548574073;27460;28950.565741513896;29541.852290738625;30234;31259.531107033185;3047.8171309721265;3083.3833233353193;3208.727258183057;3212.8714851405916;3267.803931764102;410603.9469802662;485932;487018;499051;500234;57756.844863103346;58040;58117.4188258112;58137.41862581405;58263;33626.49560256598;34471.65528344735;34502.48246276308;35295;35387.469187962204;15640.92179539095;15839.208039597974;15856.207189640703;15861.127637979791;15900.52298431049;12106.728793476734;12200.206986546023;12206.816897746541;12213.267203967791;12237.816432753516;17789.19948602316;18273.725894111598;18432;18501.53746156357;18527;7507.361874240642;7640.121386040676;7673.53958762476;7682.270184332479;7722.266384693445;845.5096044294295;908.6415808366403;965.8840939087354;1073.608133031438;1092.1317552545745;28791.56812647812;29040;29375.706242937296;29532.704672953427;29588.668509917105;895.6462197431978;903.8127730891259;957.8563215517728;968.8110818390346;1028.9485525723685;11430.25703329281;11943.581974630846;14852;15198.924005379902;15235.923820380827;755648;957243;957979;961029;961813;55271.447285527436;56117;56197;56286.43713562894;56335.71832140813;1158.7334912969895;1235.172434468908;1278.565287802133;1278.8209650648917;1281.7436512697684;23386.64920026235;29484.262893427836;30059;30305;30813.537796933513;51029.74485127551;51606.74196629143;51691.224631629804;51844.96310073702;51972;604414;607825;609111;609261;611355;25855.870720647028;25878.611820822327;26151.607725883747;26379.736202638494;26654.06710765114;43840.12319753651;44125.33811992887;44664.55335446604;44876.55123448854;45205.32192017188;28265.43469130647;31030.844845775628;35624.821875890455;36794.816025919696;37689.05777355588;31712.682873170972;32395.51406728854;37241;38052.809735951145;38818.41772373473;29734.40531189321;31758.68241317557;31774;35486.112847177996;37180.62819371772;31420.52869206918;33455.83272083624;33788.3242335147;34008.14979625529;34219.315613688086;24925.626115608644;25209.621855671812;25527.361815954006;25546.872265638554;25848.741512584635 02:56:00 INFO - PROCESS | 2296 | |2;jquery.html;364.26963937305703;369.21910160011583;374.606663003846;382.6881091910091;396.85911501416996;19014.602165491197;19904.203831846746;20223.089960951755;20280.797192028076;20459.897700511476;19891.403257902286;20236.8988155059;20305.289314873982;20582.279620213296;20710.378688639365;5583;5612.887742245152;5778.075507918723;5947.345791962881;5994.190784244137;5935.940640593603;5938.4358485943985;5986;5986.640801551921;5986.970065149668;5027.824026159085;5606.074997625387;5836.795712150076;5884.852878678024;5926.170336152942;5772.249607550992;5852.531797456215;5905.173275741399;5910.704464776787;5925.792597259076;4523.525029871849;5089.796408143669;5422.620416570841;5462.781488740446;5468.152436372368;12759.489620415265;13033.739325213537;14081.577552673334;24334.02663893442;24696.3825904352;2124.702541644171;2288.7367952685486;2322.6516022596575;2327.2320134355623;2349.060375849657;9232.723018309462;10222.9488852556;10623.681289561326;11284.097272218203;15258.694826103416;1669.5074952888926;1759.8152194019572;1813.7460755494242;1814.4284550366592;1816.827401396865;69847.65076174587;90717.54641226752;100130;113924;130751;9653.806923861483;9924;10155.949220253851;10180.643677471253;10260.794784104277;12563.93718031404;12595.496180152875;12613.117081804186;12613.369331533386;12642.873571264354;7811.609419529001;7913.762587122395;7932.920670793334;7953.403494737918;8043.7989050273045;6538.248101468297;8076.27313541784;8197.098319184885;8208.46644968075;8231.05342885564;3898.941515877264;3971.443997840247;3975.966248775281;4034.426573636284;4042.0299128209017;1820.7450956866048;1872.7378167056622;1877.2584828992472;1878.6618408686568;1882;764.323573637335;803.8914746509263;861.4960248254841;904.9366544341833;958.9616415343447;20029.399118026482;21170.57658846845;21176.258830940842;21204.83373414446;21213.681794772783;896.8385690575759;907.5371560504059;915.7435917942987;968.9244938118683;989.4805227255605;10390.636327728493;10851.240413171003;10895.564177432972;10916.235863489639;10918.945405272923;585622;600212;600593;614871;628443;100299.99700003052;131556.3422182883;134692.65307346996;136638;140380;868.6655637579578;880.8282384935004;939;1003.7691330994058;1022.6880801355509;18758.906205468884;19342.129604168127;19392.612147756685;19638.410847674593;19672.803271967095;43968.56031439645;55294;56605.71697141488;57202;57418;75497.86753198596;76926;81018;81796.0024397304;83226;7008.649567521706;7981.840363192588;8071.757847264592;8216.137305582828;8239.134890836376;7092.25531319204;9316;10869.836952445561;12385.266062751401;12617.810732839198;10503.737406564896;10920.890791091988;11040.944795275973;11307.830382544103;11311.490982905625;10671.89328106709;10710.303830251009;10801.24391292602;10895.346279223271;10954.233203675667;9272.953635231781;11096.944515277371;11231.101511879193;11293.661190164308;11504.25222360544;7905.169957154416;7996.32031277348;8017.198280171936;8141.470804397695;8179.591020449073;8507.702230421906;10800.783984320113;10845.186611004048;11030.50362733695;11287.097032237565 02:56:00 INFO - PROCESS | 2296 | |3;mootools.html;1304.726007538417;1351.1082685427616;1458.67179884526;1550.4650895441068;1609.7182992976227;7434.14507331657;7783.532988020723;7902.565358905258;8232.876506852403;8237.464564803284;8080.878786818203;8091.595420228994;8181.631826567804;8194.877076843852;8231.300339471152;2303.8617682939034;2332.790048895599;2333.6499525071204;2336.1122773346187;2341.2039906431896;2317.29322556621;2359.752226016269;2370.0519792083132;2373.7982271506944;2375.2636682628354;2306.331163962449;2307.9192228272013;2342.7657234276608;2344.6483027545833;2344.7069116360453;1947.191915355126;2259.48031952651;2286.1769762885347;2315.0045480443405;2334.568104900599;1911.9426417207505;2270.0465804362207;2326.4881726020417;2330.4989427273217;2337.637666161748;8230.547319897403;8308.584570771438;8316.625751841182;8318.209770071833;8329.875051874227;5021.447640759514;5207.609429292818;5219.973900130475;5247.895042099175;5251.632385733;3220.7584431167757;3424.8116353600544;3483.6690514401344;3573.749837511375;3578.8568457261676;919.3059240273617;1049.3756215052108;1052.9578816847318;1093.327603523835;1100.5927806711488;7982.640781164861;10695.572177112907;10770.838437423445;10875.619353322674;10875.945620271848;4230.942264433892;4356.586124318184;4431.268840641258;4446.088551846864;4470.2177119004045;8605.139486051345;8608.612612432456;8611.784705382415;8640.049594544595;8662.6101825418;4248.746619747182;4999.850004499871;5070.416902056237;5087.338645976073;5098;4051.716379853382;4270.914581708411;4427.070315233773;4429.822807087745;4693.20215563355;3110;3658.902329301199;3675.46705727668;3759.586445490994;3760.360738674432;1140.845985791924;1142.6914733022038;1143.1369316166408;1152.475623591264;1153.0602558914459;772.884067389896;853.9018012928468;879.907609700985;898.9460632361948;913.1325241021057;5298.682079075268;5451;5492.588055895823;5676.637606974296;5739.713014349266;806.5604245686116;900.4192295969194;912.8402529557301;931.2875650127658;964.840801267797;3169.904902852872;3494.8427320770625;3730.2539492101687;3795.1650636859845;3848.9037774055314;9738.853917191247;10221.335613185267;10227.488625568692;10238.283320167666;10286.074253317238;10151.086402223687;10160.237982151368;10181.33821301613;10189.98100189975;10207.132393746466;7564.281393267631;7704.499207551491;7884.29041386278;7893.842123157505;7936.246056624612;4811.422629284508;5465.836024919259;5512.310961129859;5579.744557474698;5594.468525490072;9248.583813728264;9335.346525743133;9452.480113593818;9461.574229159842;9476.905230947603;8599.484030958163;8919.821603568022;8969.955150224207;9450.52747362643;9476.668316608886;6625.436837868835;6773;7043.788686339213;7080.150381954199;7100.573965561876;7534.585597792174;7701.1913749057785;7820.648070836713;7821.100573434128;7824.64789084504;6977.895331569929;6982.197047339621;7023.859522809617;7149.0706208192605;7162.713491460491;6233.317004408694;6830.45356371499;6898.448124149955;6900.861982760216;6926;6236.126942228001;6563.507736919654;6624.867502650017;6626.237982632059;6643.136392268974;4623.953760462352;4692.7888245029735;4780.306855506002;4783.497732738012;4825.710457372569;4667.299905014275;4748.453927798348;4772.880677983078;4866.416030076271;4885.120678277802 02:56:00 INFO - PROCESS | 2296 | |4;prototype.html;273.45582291240413;289.16719846841096;304.7881722203068;307.21812985950754;311.7194524927564;4761.69049011814;5083.389993200821;5092.516210959958;5108.948910510894;5110.540051395374;3828.942565861514;4556.75972631474;4780.8565743027675;4849.442314133876;4962.6526143169995;3180.4593219152684;3287.8849240276536;3714.6099659535766;3813.199228162089;3867.911327882954;3230.78999865008;3888.4834914383273;3962.5839286874893;3966;3997.820098095578;3216.388886111631;3766.7551609145453;3900.395438707005;3915.353966595508;3930.3296099157888;3908.609139086069;3909.8045097745;3940.349842276021;3955.3473676843287;3957.683385329167;2838.9432211355866;3457.9654203458144;3458.9968909016357;3463.7748546344405;3482.355764183634;4927.51160663866;4935.842045062918;4968.677035992649;4975.825846095369;4981.67985483847;3766.7174961877695;4304.7632380219075;4440.622547083502;4466.865994020184;4534.614557762594;4608.9308660370125;4859.514048595148;4899.828506002308;4984.2025275955675;5328.733563321857;3490.947635785465;3794.0704527390967;3808.067023579241;3818.4845045919;3824.560175579816;5090.643654944192;5290.55030322423;5545.975718862586;5626.9437305626825;5649;3831.5593706723525;3851.7496362736333;3865.16898866745;3876;3902.726809123334;3435.347284016029;3811.3711237646035;3925.156091440297;3953.367461206193;3959;4215.747055176699;4283.714885534389;4480.462344518679;4500.482444518857;4502.347159661831;4299.54854740254;5339.385970613352;5345.251664766935;5391.32608423947;5449.972750136224;1024.948752562369;1140.8288756686397;1184.6919800851667;1227.5642147037852;1239.0397441982536;653.6013032050446;685.0546246180239;782.159178882692;794.7456813819638;825.6871574197055;4704.717716936994;4727.669063165546;4735.697683137165;4767.856964291077;4773.618110551148;4449.731826429452;4819.542143496433;4926.778294976734;5193.636445448858;5195.584353251731;3713.5543734752005;4495.269321311253;4626.4910859805395;4728.770519664843;4783.736894470838;2265.8361264991895;2530.7975361971;2541.567933451318;2563.231709753462;2679.5310820606314;4090.141070375195;4480.865574032784;4590.104929538775;5244.659097158673;5308.23030660552;4593.012502312019;5035.370578677665;5194;5249.921251181236;5309.5752339812725;4271.572842715704;5309.2301616266195;5336.4129945705945;5351.50157955773;5359.732013399314;4527.796249168729;5097.490250974873;5109.77365432301;5117.718525480986;5193.715184972489;4762.59517940965;4800.5199480051715;5080.441151473261;5174.663646862942;5255.395629502656;5106.259592359162;5230.921536177037;5350.7324633767375;5388.141091323487;5388.841399099212;3410.1414728973264;3528.8764893228613;3531.947020794639;3550.2012047289154;3567.732420068557;4153.91692166161;4419.381286619811;4845.248986407112;5008.84973450797;5217.478252174752;4310.069152976006;4512.571305726082;4579.061292434976;4872.634552408655;4964.702117872939;4573.748443835621;4844.467108617979;4989.301497790239;4990.975045124751;5002.4222991365095;4354.738715677069;4603.250764709389;4870.514493079645;4878.121938051185;4978.730423741923;4307.590778876001;4771.727241398205;5054.898902021865;5224.290753565011;5240.4759524047295;5211.661242019257;5212.869678258074;5219.642892847811;5229.555487783581;5300.840974770763 02:56:00 INFO - PROCESS | 2296 | |5;yui.html;688.5662032919261;698.9161300643924;754.1967804288432;759.2331744937618;785.261853857374;14145.222012789292;14602.342894569743;14642.048266862646;14646.121232726031;14646.267686615667;12830.101892867502;12884.226946383224;12894.4197511112;12927.870721292784;12982.156159849626;638.6487431912453;656.1339032477132;660.851308455596;790.182161462886;803.5379656697393;819.5779173725506;821.1583127294512;829.1501211258447;831.0609011816639;832.6835802395082;873.7640836974014;875.4703404440318;876.1588874680315;876.4040452492303;877.3945977275691;665.7969319357594;690.3096903096903;869.5608717597604;870.2776695342882;872.8167084912176;511.74924287099424;512.2623422271917;512.6155383462403;609.8719268953525;626.1265534579236;13498.122622029538;13925.442982280696;13985.580432586938;14012.859871401257;14014.089084209494;13041.608751737464;13133.540326088636;13224.86775132246;13298.667533311647;13483.460661573525;11566;11889.643310700692;11912.583059592873;12226.20529665569;12324.260544367367;4288.592583704543;4344.152890186415;4370.38814565961;4503.549645035503;4522.118186953546;89595;90889.54555227181;95161;95198.09603807954;95773.52113239389;10392.948035259775;10412.843807342895;10462.633807816843;10503;10580.788384232272;19971.60056798856;20313.390598282076;20378.675386099858;20554.58890822175;20917.37247882566;7501.699932002659;7565.146254838576;7605.23947605235;7755.612219389008;7755.728549500854;4047.514298284225;4292.656587472995;4301.333293339538;4337.200061974246;4381.233284175257;3223.1458663454205;3302.8678852846097;3653.12325041988;3829.2341531693774;3833.559140698855;668.7224801707237;677.4817264792438;688.5696439725172;688.8760023195773;689.8551304226069;583.0554501707177;596.7344531683447;646.7380710812129;649.3636236488268;676.7665155521337;8948.284137269004;9112.952010518742;9147.54262286883;9382.157866432974;9385.67150149755;652.3476523476523;655.5574986883835;656.7931101703044;666.1207206487487;677.4580335731396;4242.17277630859;4602.654800889946;4618.284165954283;4619.953800461953;4656.8137274508645;89915.55042224878;93288;115003;120432;122049;97555;100222;101974;102902;108659.45670271598;58411.4158858406;59456.70271648614;66361.01375438117;88807.55596221978;91282.54358728297;8775.42959707617;8795.164459376349;8898.06570310134;9026.954865225633;9070.63717451321;55770.72114639401;56592.43407566036;57396.71301643465;57916;59890.70054649699;67005.66497167484;67962;82031;82326.58836705778;83178;11143.442827858737;11300.321980681185;11380.487878045342;11410.201285909907;11812.70468238301;13228.338583071001;13448.663783405493;13845.86154138486;14007.509737159151;14028.088174269049;12793.360331983178;12825.08656483735;12854.935725321313;13335.33308336458;13353.265570393722;10527.420991845522;12454.688632784253;12492.562760303346;13186.472541098248;13485.325733713078;12029.939850300692;12819.551315703906;13072.934635326763;13202;13573.04988650785;9909.504524773876;10218.846717299395;10550.261481696207;10599.470026498799;11178.496967636313;10313.587456501657;11311.377874216998;11328.660140195807;11370.08360795509;11447.484863181011 02:56:00 INFO - PROCESS | 2296 | __end_tp_report 02:56:00 INFO - PROCESS | 2296 | __start_cc_report 02:56:00 INFO - PROCESS | 2296 | _x_x_mozilla_cycle_collect,2510 02:56:00 INFO - PROCESS | 2296 | __end_cc_report 02:56:00 INFO - PROCESS | 2296 | __startTimestamp1483872960221__endTimestamp 02:56:00 INFO - PROCESS | 2296 | 02:56:00 INFO - PROCESS | 2296 | ------- Summary: start ------- 02:56:00 INFO - PROCESS | 2296 | Number of tests: 6 02:56:00 INFO - PROCESS | 2296 | 02:56:00 INFO - PROCESS | 2296 | [#0] dojo.html Cycles:165 Average:5535.52 Median:5762.34 stddev:621.69 (10.8%) stddev-sans-first:596.01 02:56:00 INFO - PROCESS | 2296 | Values: 3200.9 3214.9 3215.4 3237.0 3265.2 5465.0 5477.2 5531.4 5534.6 5547.0 5453.7 5513.4 5545.3 5553.8 5603.5 5007.1 5015.7 5043.7 5129.3 5134.6 5013.7 5016.7 5038.8 5039.8 5121.6 5029.6 5070.6 5082.7 5093.5 5096.3 5059.4 5102.8 5125.7 5133.8 5174.8 4818.4 5046.4 5059.3 5080.4 5090.2 5338.4 5381.2 5405.8 5422.4 5427.9 5983.5 6000.6 6045.1 6071.6 6097.0 6148.4 6152.3 6159.8 6162.8 6169.0 5524.4 5573.9 5582.9 5585.3 5632.9 5166.8 6192.9 6228.3 6242.2 6261.9 5877.3 5878.5 5878.6 5888.0 5893.4 5812.0 5856.2 5861.0 5874.9 6065.8 5831.3 5831.7 5833.6 5833.8 5836.2 5786.0 5874.5 5875.2 5880.8 5890.0 4733.2 4743.0 4750.7 4754.7 4758.2 4277.9 4290.4 4303.2 4309.3 4322.9 5514.0 5538.4 5540.9 5543.0 5543.6 6043.6 6055.2 6058.4 6063.6 6074.5 5542.2 5559.9 5566.0 5573.3 5591.8 5157.1 5198.9 5243.1 5249.9 5268.9 6167.4 6191.6 6231.0 6233.2 6262.1 6080.8 6106.7 6114.7 6125.3 6146.7 4628.6 5018.4 5225.5 6273.7 6298.7 5744.5 5769.2 5836.9 5894.7 5951.9 6007.1 6043.1 6047.6 6053.9 6063.4 5826.4 5861.0 5862.9 5864.0 5879.1 5923.4 5966.1 5966.8 5967.2 5977.2 5761.7 5783.3 5799.8 5802.9 5815.5 5801.4 5814.2 5864.1 5936.7 5950.3 5742.8 5762.3 5806.1 5817.8 5822.3 02:56:00 INFO - PROCESS | 2296 | 02:56:00 INFO - PROCESS | 2296 | [#1] ext.html Cycles:180 Average:76808.82 Median:21086.27 stddev:187874.49 (891.0%) stddev-sans-first:188314.04 02:56:00 INFO - PROCESS | 2296 | Values: 444.6 472.3 480.5 490.2 498.9 35958.8 37296.8 38812.8 38853.8 39132.6 39709.2 40115.2 40131.8 40216.4 40234.0 9176.6 9220.0 9258.2 9261.3 9425.2 9390.5 9503.8 9510.7 9510.7 9520.9 9433.0 9509.5 9555.8 9577.0 9607.2 9471.6 9519.4 9541.7 9554.4 9564.6 9330.1 9407.6 9410.3 9415.4 9421.3 37437.6 37899.6 38363.0 38395.0 38400.4 2930.0 3134.1 3231.7 3443.9 3809.5 27460.0 28950.6 29541.9 30234.0 31259.5 3047.8 3083.4 3208.7 3212.9 3267.8 410603.9 485932.0 487018.0 499051.0 500234.0 57756.8 58040.0 58117.4 58137.4 58263.0 33626.5 34471.7 34502.5 35295.0 35387.5 15640.9 15839.2 15856.2 15861.1 15900.5 12106.7 12200.2 12206.8 12213.3 12237.8 17789.2 18273.7 18432.0 18501.5 18527.0 7507.4 7640.1 7673.5 7682.3 7722.3 845.5 908.6 965.9 1073.6 1092.1 28791.6 29040.0 29375.7 29532.7 29588.7 895.6 903.8 957.9 968.8 1028.9 11430.3 11943.6 14852.0 15198.9 15235.9 755648.0 957243.0 957979.0 961029.0 961813.0 55271.4 56117.0 56197.0 56286.4 56335.7 1158.7 1235.2 1278.6 1278.8 1281.7 23386.6 29484.3 30059.0 30305.0 30813.5 51029.7 51606.7 51691.2 51845.0 51972.0 604414.0 607825.0 609111.0 609261.0 611355.0 25855.9 25878.6 26151.6 26379.7 26654.1 43840.1 44125.3 44664.6 44876.6 45205.3 28265.4 31030.8 35624.8 36794.8 37689.1 31712.7 32395.5 37241.0 38052.8 38818.4 29734.4 31758.7 31774.0 35486.1 37180.6 31420.5 33455.8 33788.3 34008.1 34219.3 24925.6 25209.6 25527.4 25546.9 25848.7 02:56:00 INFO - PROCESS | 2296 | 02:56:00 INFO - PROCESS | 2296 | [#2] jquery.html Cycles:180 Average:34433.09 Median:378.65 stddev:100965.24 (26664.7%) stddev-sans-first:101216.07 02:56:00 INFO - PROCESS | 2296 | Values: 364.3 369.2 374.6 382.7 396.9 19014.6 19904.2 20223.1 20280.8 20459.9 19891.4 20236.9 20305.3 20582.3 20710.4 5583.0 5612.9 5778.1 5947.3 5994.2 5935.9 5938.4 5986.0 5986.6 5987.0 5027.8 5606.1 5836.8 5884.9 5926.2 5772.2 5852.5 5905.2 5910.7 5925.8 4523.5 5089.8 5422.6 5462.8 5468.2 12759.5 13033.7 14081.6 24334.0 24696.4 2124.7 2288.7 2322.7 2327.2 2349.1 9232.7 10222.9 10623.7 11284.1 15258.7 1669.5 1759.8 1813.7 1814.4 1816.8 69847.7 90717.5 100130.0 113924.0 130751.0 9653.8 9924.0 10155.9 10180.6 10260.8 12563.9 12595.5 12613.1 12613.4 12642.9 7811.6 7913.8 7932.9 7953.4 8043.8 6538.2 8076.3 8197.1 8208.5 8231.1 3898.9 3971.4 3976.0 4034.4 4042.0 1820.7 1872.7 1877.3 1878.7 1882.0 764.3 803.9 861.5 904.9 959.0 20029.4 21170.6 21176.3 21204.8 21213.7 896.8 907.5 915.7 968.9 989.5 10390.6 10851.2 10895.6 10916.2 10918.9 585622.0 600212.0 600593.0 614871.0 628443.0 100300.0 131556.3 134692.7 136638.0 140380.0 868.7 880.8 939.0 1003.8 1022.7 18758.9 19342.1 19392.6 19638.4 19672.8 43968.6 55294.0 56605.7 57202.0 57418.0 75497.9 76926.0 81018.0 81796.0 83226.0 7008.6 7981.8 8071.8 8216.1 8239.1 7092.3 9316.0 10869.8 12385.3 12617.8 10503.7 10920.9 11040.9 11307.8 11311.5 10671.9 10710.3 10801.2 10895.3 10954.2 9273.0 11096.9 11231.1 11293.7 11504.3 7905.2 7996.3 8017.2 8141.5 8179.6 8507.7 10800.8 10845.2 11030.5 11287.1 02:56:00 INFO - PROCESS | 2296 | 02:56:00 INFO - PROCESS | 2296 | [#3] mootools.html Cycles:180 Average:5325.36 Median:5045.93 stddev:2930.96 (58.1%) stddev-sans-first:2923.61 02:56:00 INFO - PROCESS | 2296 | Values: 1304.7 1351.1 1458.7 1550.5 1609.7 7434.1 7783.5 7902.6 8232.9 8237.5 8080.9 8091.6 8181.6 8194.9 8231.3 2303.9 2332.8 2333.6 2336.1 2341.2 2317.3 2359.8 2370.1 2373.8 2375.3 2306.3 2307.9 2342.8 2344.6 2344.7 1947.2 2259.5 2286.2 2315.0 2334.6 1911.9 2270.0 2326.5 2330.5 2337.6 8230.5 8308.6 8316.6 8318.2 8329.9 5021.4 5207.6 5220.0 5247.9 5251.6 3220.8 3424.8 3483.7 3573.7 3578.9 919.3 1049.4 1053.0 1093.3 1100.6 7982.6 10695.6 10770.8 10875.6 10875.9 4230.9 4356.6 4431.3 4446.1 4470.2 8605.1 8608.6 8611.8 8640.0 8662.6 4248.7 4999.9 5070.4 5087.3 5098.0 4051.7 4270.9 4427.1 4429.8 4693.2 3110.0 3658.9 3675.5 3759.6 3760.4 1140.8 1142.7 1143.1 1152.5 1153.1 772.9 853.9 879.9 898.9 913.1 5298.7 5451.0 5492.6 5676.6 5739.7 806.6 900.4 912.8 931.3 964.8 3169.9 3494.8 3730.3 3795.2 3848.9 9738.9 10221.3 10227.5 10238.3 10286.1 10151.1 10160.2 10181.3 10190.0 10207.1 7564.3 7704.5 7884.3 7893.8 7936.2 4811.4 5465.8 5512.3 5579.7 5594.5 9248.6 9335.3 9452.5 9461.6 9476.9 8599.5 8919.8 8970.0 9450.5 9476.7 6625.4 6773.0 7043.8 7080.2 7100.6 7534.6 7701.2 7820.6 7821.1 7824.6 6977.9 6982.2 7023.9 7149.1 7162.7 6233.3 6830.5 6898.4 6900.9 6926.0 6236.1 6563.5 6624.9 6626.2 6643.1 4624.0 4692.8 4780.3 4783.5 4825.7 4667.3 4748.5 4772.9 4866.4 4885.1 02:56:00 INFO - PROCESS | 2296 | 02:56:00 INFO - PROCESS | 2296 | [#4] prototype.html Cycles:180 Average:4178.90 Median:4606.09 stddev:1255.93 (27.3%) stddev-sans-first:1224.77 02:56:00 INFO - PROCESS | 2296 | Values: 273.5 289.2 304.8 307.2 311.7 4761.7 5083.4 5092.5 5108.9 5110.5 3828.9 4556.8 4780.9 4849.4 4962.7 3180.5 3287.9 3714.6 3813.2 3867.9 3230.8 3888.5 3962.6 3966.0 3997.8 3216.4 3766.8 3900.4 3915.4 3930.3 3908.6 3909.8 3940.3 3955.3 3957.7 2838.9 3458.0 3459.0 3463.8 3482.4 4927.5 4935.8 4968.7 4975.8 4981.7 3766.7 4304.8 4440.6 4466.9 4534.6 4608.9 4859.5 4899.8 4984.2 5328.7 3490.9 3794.1 3808.1 3818.5 3824.6 5090.6 5290.6 5546.0 5626.9 5649.0 3831.6 3851.7 3865.2 3876.0 3902.7 3435.3 3811.4 3925.2 3953.4 3959.0 4215.7 4283.7 4480.5 4500.5 4502.3 4299.5 5339.4 5345.3 5391.3 5450.0 1024.9 1140.8 1184.7 1227.6 1239.0 653.6 685.1 782.2 794.7 825.7 4704.7 4727.7 4735.7 4767.9 4773.6 4449.7 4819.5 4926.8 5193.6 5195.6 3713.6 4495.3 4626.5 4728.8 4783.7 2265.8 2530.8 2541.6 2563.2 2679.5 4090.1 4480.9 4590.1 5244.7 5308.2 4593.0 5035.4 5194.0 5249.9 5309.6 4271.6 5309.2 5336.4 5351.5 5359.7 4527.8 5097.5 5109.8 5117.7 5193.7 4762.6 4800.5 5080.4 5174.7 5255.4 5106.3 5230.9 5350.7 5388.1 5388.8 3410.1 3528.9 3531.9 3550.2 3567.7 4153.9 4419.4 4845.2 5008.8 5217.5 4310.1 4512.6 4579.1 4872.6 4964.7 4573.7 4844.5 4989.3 4991.0 5002.4 4354.7 4603.3 4870.5 4878.1 4978.7 4307.6 4771.7 5054.9 5224.3 5240.5 5211.7 5212.9 5219.6 5229.6 5300.8 02:56:00 INFO - PROCESS | 2296 | 02:56:00 INFO - PROCESS | 2296 | [#5] yui.html Cycles:180 Average:20617.40 Median:4512.83 stddev:30479.69 (675.4%) stddev-sans-first:30528.46 02:56:00 INFO - PROCESS | 2296 | Values: 688.6 698.9 754.2 759.2 785.3 14145.2 14602.3 14642.0 14646.1 14646.3 12830.1 12884.2 12894.4 12927.9 12982.2 638.6 656.1 660.9 790.2 803.5 819.6 821.2 829.2 831.1 832.7 873.8 875.5 876.2 876.4 877.4 665.8 690.3 869.6 870.3 872.8 511.7 512.3 512.6 609.9 626.1 13498.1 13925.4 13985.6 14012.9 14014.1 13041.6 13133.5 13224.9 13298.7 13483.5 11566.0 11889.6 11912.6 12226.2 12324.3 4288.6 4344.2 4370.4 4503.5 4522.1 89595.0 90889.5 95161.0 95198.1 95773.5 10392.9 10412.8 10462.6 10503.0 10580.8 19971.6 20313.4 20378.7 20554.6 20917.4 7501.7 7565.1 7605.2 7755.6 7755.7 4047.5 4292.7 4301.3 4337.2 4381.2 3223.1 3302.9 3653.1 3829.2 3833.6 668.7 677.5 688.6 688.9 689.9 583.1 596.7 646.7 649.4 676.8 8948.3 9113.0 9147.5 9382.2 9385.7 652.3 655.6 656.8 666.1 677.5 4242.2 4602.7 4618.3 4620.0 4656.8 89915.6 93288.0 115003.0 120432.0 122049.0 97555.0 100222.0 101974.0 102902.0 108659.5 58411.4 59456.7 66361.0 88807.6 91282.5 8775.4 8795.2 8898.1 9027.0 9070.6 55770.7 56592.4 57396.7 57916.0 59890.7 67005.7 67962.0 82031.0 82326.6 83178.0 11143.4 11300.3 11380.5 11410.2 11812.7 13228.3 13448.7 13845.9 14007.5 14028.1 12793.4 12825.1 12854.9 13335.3 13353.3 10527.4 12454.7 12492.6 13186.5 13485.3 12029.9 12819.6 13072.9 13202.0 13573.0 9909.5 10218.8 10550.3 10599.5 11178.5 10313.6 11311.4 11328.7 11370.1 11447.5 02:56:00 INFO - PROCESS | 2296 | -------- Summary: end -------- 02:56:00 INFO - PROCESS | 2296 | 02:56:00 INFO - TEST-INFO | 2296: exit 0 02:56:00 INFO - TEST-OK | dromaeo_css | took 1120279ms 02:56:00 INFO - TEST-START | kraken 02:56:00 INFO - operating with platform_type : mac_ 02:56:00 INFO - Initialising browser for kraken test... 02:56:00 INFO - TEST-INFO | started process 2300 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpwgB_rn/profile http://localhost:50044/getInfo.html) 02:56:03 INFO - PROCESS | 2300 | __metrics Screen width/height:1600/1200 02:56:03 INFO - PROCESS | 2300 | colorDepth:24 02:56:03 INFO - PROCESS | 2300 | Browser inner width/height: 1024/689 02:56:03 INFO - PROCESS | 2300 | __metrics 02:56:03 INFO - PROCESS | 2300 | 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" 02:56:03 INFO - PROCESS | 2300 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:56:04 INFO - TEST-INFO | 2300: exit 0 02:56:04 INFO - Browser initialized. 02:56:04 INFO - Running cycle 1/1 for kraken test... 02:56:04 INFO - TEST-INFO | started process 2302 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/T/tmpwgB_rn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:56:17 INFO - PROCESS | 2302 | RSS: Main: 223338496 02:56:17 INFO - PROCESS | 2302 | 02:56:19 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50044/tests/kraken/driver.html?testName=audio-beat-detection) 02:56:20 INFO - PROCESS | 2302 | RSS: Main: 272654336 02:56:20 INFO - PROCESS | 2302 | 02:56:23 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50044/tests/kraken/driver.html?testName=audio-dft) 02:56:23 INFO - PROCESS | 2302 | RSS: Main: 286081024 02:56:23 INFO - PROCESS | 2302 | 02:56:27 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50044/tests/kraken/driver.html?testName=audio-fft) 02:56:27 INFO - PROCESS | 2302 | RSS: Main: 381784064 02:56:27 INFO - PROCESS | 2302 | 02:56:29 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50044/tests/kraken/driver.html?testName=audio-oscillator) 02:56:30 INFO - PROCESS | 2302 | RSS: Main: 283152384 02:56:30 INFO - PROCESS | 2302 | 02:56:32 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50044/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:56:32 INFO - PROCESS | 2302 | RSS: Main: 284278784 02:56:32 INFO - PROCESS | 2302 | 02:56:35 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50044/tests/kraken/driver.html?testName=imaging-darkroom) 02:56:36 INFO - PROCESS | 2302 | RSS: Main: 325804032 02:56:36 INFO - PROCESS | 2302 | 02:56:39 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50044/tests/kraken/driver.html?testName=imaging-desaturate) 02:56:39 INFO - PROCESS | 2302 | RSS: Main: 326180864 02:56:39 INFO - PROCESS | 2302 | 02:56:42 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50044/tests/kraken/driver.html?testName=json-parse-financial) 02:56:43 INFO - PROCESS | 2302 | RSS: Main: 325566464 02:56:43 INFO - PROCESS | 2302 | 02:56:44 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50044/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:56:45 INFO - PROCESS | 2302 | RSS: Main: 299601920 02:56:45 INFO - PROCESS | 2302 | 02:56:47 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50044/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:56:48 INFO - PROCESS | 2302 | RSS: Main: 300363776 02:56:48 INFO - PROCESS | 2302 | 02:56:50 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50044/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:56:51 INFO - PROCESS | 2302 | RSS: Main: 303669248 02:56:51 INFO - PROCESS | 2302 | 02:56:54 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:56:54 INFO - PROCESS | 2302 | RSS: Main: 321585152 02:56:54 INFO - PROCESS | 2302 | 02:56:57 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:56:58 INFO - PROCESS | 2302 | RSS: Main: 314408960 02:56:58 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50044/tests/kraken/driver.html?testName=ai-astar) 02:57:00 INFO - PROCESS | 2302 | RSS: Main: 358117376 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | __start_tp_report 02:57:00 INFO - PROCESS | 2302 | _x_x_mozilla_page_load 02:57:00 INFO - PROCESS | 2302 | _x_x_mozilla_page_load_details 02:57:00 INFO - PROCESS | 2302 | |i|pagename|runs| 02:57:00 INFO - PROCESS | 2302 | |0;ai-astar;116;114;113;116;114;113;115;114;111;113 02:57:00 INFO - PROCESS | 2302 | |1;audio-beat-detection;171;141;141;146;111;111;110;109;141;109 02:57:00 INFO - PROCESS | 2302 | |2;audio-dft;186;186;186;187;186;187;184;185;187;186 02:57:00 INFO - PROCESS | 2302 | |3;audio-fft;83;61;59;89;61;60;59;58;82;59 02:57:00 INFO - PROCESS | 2302 | |4;audio-oscillator;89;71;95;70;67;69;89;67;69;91 02:57:00 INFO - PROCESS | 2302 | |5;imaging-gaussian-blur;87;84;87;84;86;84;88;85;87;84 02:57:00 INFO - PROCESS | 2302 | |6;imaging-darkroom;94;94;94;94;93;93;94;108;148;96 02:57:00 INFO - PROCESS | 2302 | |7;imaging-desaturate;85;117;78;79;82;80;79;112;128;97 02:57:00 INFO - PROCESS | 2302 | |8;json-parse-financial;59;57;63;55;54;57;58;58;55;57 02:57:00 INFO - PROCESS | 2302 | |9;json-stringify-tinderbox;68;67;76;75;73;71;72;72;72;72 02:57:00 INFO - PROCESS | 2302 | |10;stanford-crypto-aes;85;92;103;80;82;103;102;103;78;94 02:57:00 INFO - PROCESS | 2302 | |11;stanford-crypto-ccm;142;139;142;156;153;136;142;144;190;149 02:57:00 INFO - PROCESS | 2302 | |12;stanford-crypto-pbkdf2;168;165;185;169;167;164;164;166;164;164 02:57:00 INFO - PROCESS | 2302 | |13;stanford-crypto-sha256-iterative;67;69;69;68;67;67;69;81;83;87 02:57:00 INFO - PROCESS | 2302 | __end_tp_report 02:57:00 INFO - PROCESS | 2302 | __start_cc_report 02:57:00 INFO - PROCESS | 2302 | _x_x_mozilla_cycle_collect,2058 02:57:00 INFO - PROCESS | 2302 | __end_cc_report 02:57:00 INFO - PROCESS | 2302 | __startTimestamp1483873020462__endTimestamp 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | ------- Summary: start ------- 02:57:00 INFO - PROCESS | 2302 | Number of tests: 14 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:1.52 (1.3%) stddev-sans-first:1.41 02:57:00 INFO - PROCESS | 2302 | Values: 116.0 114.0 113.0 116.0 114.0 113.0 115.0 114.0 111.0 113.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:141.00 stddev:21.84 (15.5%) stddev-sans-first:17.08 02:57:00 INFO - PROCESS | 2302 | Values: 171.0 141.0 141.0 146.0 111.0 111.0 110.0 109.0 141.0 109.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#2] audio-dft Cycles:10 Average:186.00 Median:186.00 stddev:0.94 (0.5%) stddev-sans-first:1.00 02:57:00 INFO - PROCESS | 2302 | Values: 186.0 186.0 186.0 187.0 186.0 187.0 184.0 185.0 187.0 186.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#3] audio-fft Cycles:10 Average:67.10 Median:61.00 stddev:12.29 (20.1%) stddev-sans-first:11.61 02:57:00 INFO - PROCESS | 2302 | Values: 83.0 61.0 59.0 89.0 61.0 60.0 59.0 58.0 82.0 59.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:80.00 stddev:11.62 (14.5%) stddev-sans-first:11.59 02:57:00 INFO - PROCESS | 2302 | Values: 89.0 71.0 95.0 70.0 67.0 69.0 89.0 67.0 69.0 91.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.58 (1.8%) stddev-sans-first:1.59 02:57:00 INFO - PROCESS | 2302 | Values: 87.0 84.0 87.0 84.0 86.0 84.0 88.0 85.0 87.0 84.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:94.00 stddev:17.18 (18.3%) stddev-sans-first:18.04 02:57:00 INFO - PROCESS | 2302 | Values: 94.0 94.0 94.0 94.0 93.0 93.0 94.0 108.0 148.0 96.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:79.50 stddev:18.69 (23.5%) stddev-sans-first:19.56 02:57:00 INFO - PROCESS | 2302 | Values: 85.0 117.0 78.0 79.0 82.0 80.0 79.0 112.0 128.0 97.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:57.50 stddev:2.54 (4.4%) stddev-sans-first:2.62 02:57:00 INFO - PROCESS | 2302 | Values: 59.0 57.0 63.0 55.0 54.0 57.0 58.0 58.0 55.0 57.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.00 stddev:2.74 (3.8%) stddev-sans-first:2.54 02:57:00 INFO - PROCESS | 2302 | Values: 68.0 67.0 76.0 75.0 73.0 71.0 72.0 72.0 72.0 72.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:81.00 stddev:10.30 (12.7%) stddev-sans-first:10.59 02:57:00 INFO - PROCESS | 2302 | Values: 85.0 92.0 103.0 80.0 82.0 103.0 102.0 103.0 78.0 94.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:146.50 stddev:15.59 (10.6%) stddev-sans-first:16.31 02:57:00 INFO - PROCESS | 2302 | Values: 142.0 139.0 142.0 156.0 153.0 136.0 142.0 144.0 190.0 149.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:166.50 stddev:6.38 (3.8%) stddev-sans-first:6.77 02:57:00 INFO - PROCESS | 2302 | Values: 168.0 165.0 185.0 169.0 167.0 164.0 164.0 166.0 164.0 164.0 02:57:00 INFO - PROCESS | 2302 | 02:57:00 INFO - PROCESS | 2302 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:69.00 stddev:7.75 (11.2%) stddev-sans-first:7.94 02:57:00 INFO - PROCESS | 2302 | Values: 67.0 69.0 69.0 68.0 67.0 67.0 69.0 81.0 83.0 87.0 02:57:00 INFO - PROCESS | 2302 | -------- Summary: end -------- 02:57:00 INFO - PROCESS | 2302 | 02:57:01 INFO - TEST-INFO | 2302: exit 0 02:57:01 INFO - TEST-OK | kraken | took 60659ms 02:57:01 INFO - SUITE-END | took 1180s 02:57:01 INFO - Completed test suite (00:19:40) 02:57:01 INFO - javascript benchmark 02:57:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3200.9039728808116, 3214.887478938236, 3215.356928614278, 3236.983815080924, 3265.167382317509, 5464.98897703925, 5477.178423236516, 5531.3915469298345, 5534.584906132041, 5547.0, 5453.7099013956495, 5513.365962914266, 5545.306836645419, 5553.805616803413, 5603.467670571299, 5007.123753343169, 5015.673981191211, 5043.747812609373, 5129.28190053391, 5134.589232861381, 5013.699178049311, 5016.69899806011, 5038.84883453497, 5039.84880453587, 5121.641485096045, 5029.597632189434, 5070.645054846163, 5082.720450375228, 5093.465186155438, 5096.286519887219, 5059.443461219282, 5102.846914592568, 5125.718085505296, 5133.768980395891, 5174.844754657366, 4818.4217893852965, 5046.394432668067, 5059.342285502861, 5080.415752188509, 5090.236464530313, 5338.386085600128, 5381.192821076831, 5405.837824865221, 5422.40353561108, 5427.864303392407, 5983.491403230731, 6000.609960352606, 6045.123457098696, 6071.574989750719, 6096.963516202212, 6148.38516148386, 6152.261728592597, 6159.846003849895, 6162.815115546541, 6168.96915515424, 5524.364698059734, 5573.916391254134, 5582.91625625616, 5585.301837270301, 5632.859178520488, 5166.767495462713, 6192.8761424771255, 6228.34602366745, 6242.15730876335, 6261.8747625047245, 5877.324107727581, 5878.5297176225795, 5878.588498805043, 5887.970560147258, 5893.381194974551, 5812.0119579671555, 5856.238688970492, 5860.970695146497, 5874.9118763218585, 6065.787697430569, 5831.329397119301, 5831.708414579254, 5833.562482813806, 5833.766649334064, 5836.182934389104, 5785.971070144707, 5874.500667443315, 5875.177475153482, 5880.794172203952, 5890.028145356054, 4733.219018861918, 4742.9762851186215, 4750.7387093709485, 4754.738489383048, 4758.214894542361, 4277.893052673708, 4290.377895205178, 4303.225419424533, 4309.288967320357, 4322.891927701832, 5514.0, 5538.446155384429, 5540.889182216333, 5542.972285138549, 5543.584231182677, 6043.607165534227, 6055.2430946131735, 6058.363871793486, 6063.575549711478, 6074.483668888106, 5542.196381524656, 5559.944400555943, 5565.972170139124, 5573.27547418833, 5591.804286850022, 5157.123289040872, 5198.948010519922, 5243.056249875059, 5249.895002100013, 5268.894622107537, 6167.414095660905, 6191.628502289877, 6231.034189700604, 6233.189685340877, 6262.060690896402, 6080.847978800565, 6106.725197366218, 6114.7248373822395, 6125.326214116532, 6146.661933593517, 4628.60656844172, 5018.44797072329, 5225.529702326809, 6273.65494897785, 6298.653574053471, 5744.511716504144, 5769.249997500466, 5836.9416305838095, 5894.734736936763, 5951.91072133927, 6007.098935159761, 6043.093535969464, 6047.606905551125, 6053.939460605337, 6063.423974722395, 5826.388229235869, 5861.0, 5862.853428664317, 5864.0, 5879.118132280192, 5923.437273459014, 5966.105084237399, 5966.791162309472, 5967.1944287522365, 5977.193078934288, 5761.711914404347, 5783.334916484658, 5799.797007104731, 5802.883942321215, 5815.476607105212, 5801.448862358069, 5814.244148260699, 5864.091065884794, 5936.703164841654, 5950.256217972754, 5742.827715168552, 5762.337331206797, 5806.071028635398, 5817.76728930838, 5822.301323841166], "value": 5496.2644154392165, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.6198500282258, 472.30335255498125, 480.52668121899944, 490.2156549520767, 498.86780003299134, 35958.82020589897, 37296.813515932416, 38812.80593597032, 38853.805730971304, 39132.60867391325, 39709.205815883666, 40115.19769604606, 40131.79934100326, 40216.396754048714, 40234.0, 9176.58705358257, 9219.95390023049, 9258.21305189058, 9261.305402094837, 9425.19885809707, 9390.530473476332, 9503.762405939873, 9510.66712665057, 9510.714678559654, 9520.8571871422, 9433.009533998935, 9509.524523773818, 9555.80888382232, 9576.994415586367, 9607.231421486264, 9471.573779179953, 9519.428834269896, 9541.713748587554, 9554.378965367263, 9564.617415303379, 9330.113639204264, 9407.57665905029, 9410.341276110676, 9415.387999780061, 9421.340506164572, 37437.62562374368, 37899.621003789885, 38363.0, 38395.0, 38400.42399364012, 2929.956050659242, 3134.1067795678123, 3231.709146176832, 3443.8794642187404, 3809.5428548574073, 27460.0, 28950.565741513896, 29541.852290738625, 30234.0, 31259.531107033185, 3047.8171309721265, 3083.3833233353193, 3208.727258183057, 3212.8714851405916, 3267.803931764102, 410603.9469802662, 485932.0, 487018.0, 499051.0, 500234.0, 57756.844863103346, 58040.0, 58117.4188258112, 58137.41862581405, 58263.0, 33626.49560256598, 34471.65528344735, 34502.48246276308, 35295.0, 35387.469187962204, 15640.92179539095, 15839.208039597974, 15856.207189640703, 15861.127637979791, 15900.52298431049, 12106.728793476734, 12200.206986546023, 12206.816897746541, 12213.267203967791, 12237.816432753516, 17789.19948602316, 18273.725894111598, 18432.0, 18501.53746156357, 18527.0, 7507.361874240642, 7640.121386040676, 7673.53958762476, 7682.270184332479, 7722.266384693445, 845.5096044294295, 908.6415808366403, 965.8840939087354, 1073.608133031438, 1092.1317552545745, 28791.56812647812, 29040.0, 29375.706242937296, 29532.704672953427, 29588.668509917105, 895.6462197431978, 903.8127730891259, 957.8563215517728, 968.8110818390346, 1028.9485525723685, 11430.25703329281, 11943.581974630846, 14852.0, 15198.924005379902, 15235.923820380827, 755648.0, 957243.0, 957979.0, 961029.0, 961813.0, 55271.447285527436, 56117.0, 56197.0, 56286.43713562894, 56335.71832140813, 1158.7334912969895, 1235.172434468908, 1278.565287802133, 1278.8209650648917, 1281.7436512697684, 23386.64920026235, 29484.262893427836, 30059.0, 30305.0, 30813.537796933513, 51029.74485127551, 51606.74196629143, 51691.224631629804, 51844.96310073702, 51972.0, 604414.0, 607825.0, 609111.0, 609261.0, 611355.0, 25855.870720647028, 25878.611820822327, 26151.607725883747, 26379.736202638494, 26654.06710765114, 43840.12319753651, 44125.33811992887, 44664.55335446604, 44876.55123448854, 45205.32192017188, 28265.43469130647, 31030.844845775628, 35624.821875890455, 36794.816025919696, 37689.05777355588, 31712.682873170972, 32395.51406728854, 37241.0, 38052.809735951145, 38818.41772373473, 29734.40531189321, 31758.68241317557, 31774.0, 35486.112847177996, 37180.62819371772, 31420.52869206918, 33455.83272083624, 33788.3242335147, 34008.14979625529, 34219.315613688086, 24925.626115608644, 25209.621855671812, 25527.361815954006, 25546.872265638554, 25848.741512584635], "value": 19149.555736579932, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.26963937305703, 369.21910160011583, 374.606663003846, 382.6881091910091, 396.85911501416996, 19014.602165491197, 19904.203831846746, 20223.089960951755, 20280.797192028076, 20459.897700511476, 19891.403257902286, 20236.8988155059, 20305.289314873982, 20582.279620213296, 20710.378688639365, 5583.0, 5612.887742245152, 5778.075507918723, 5947.345791962881, 5994.190784244137, 5935.940640593603, 5938.4358485943985, 5986.0, 5986.640801551921, 5986.970065149668, 5027.824026159085, 5606.074997625387, 5836.795712150076, 5884.852878678024, 5926.170336152942, 5772.249607550992, 5852.531797456215, 5905.173275741399, 5910.704464776787, 5925.792597259076, 4523.525029871849, 5089.796408143669, 5422.620416570841, 5462.781488740446, 5468.152436372368, 12759.489620415265, 13033.739325213537, 14081.577552673334, 24334.02663893442, 24696.3825904352, 2124.702541644171, 2288.7367952685486, 2322.6516022596575, 2327.2320134355623, 2349.060375849657, 9232.723018309462, 10222.9488852556, 10623.681289561326, 11284.097272218203, 15258.694826103416, 1669.5074952888926, 1759.8152194019572, 1813.7460755494242, 1814.4284550366592, 1816.827401396865, 69847.65076174587, 90717.54641226752, 100130.0, 113924.0, 130751.0, 9653.806923861483, 9924.0, 10155.949220253851, 10180.643677471253, 10260.794784104277, 12563.93718031404, 12595.496180152875, 12613.117081804186, 12613.369331533386, 12642.873571264354, 7811.609419529001, 7913.762587122395, 7932.920670793334, 7953.403494737918, 8043.7989050273045, 6538.248101468297, 8076.27313541784, 8197.098319184885, 8208.46644968075, 8231.05342885564, 3898.941515877264, 3971.443997840247, 3975.966248775281, 4034.426573636284, 4042.0299128209017, 1820.7450956866048, 1872.7378167056622, 1877.2584828992472, 1878.6618408686568, 1882.0, 764.323573637335, 803.8914746509263, 861.4960248254841, 904.9366544341833, 958.9616415343447, 20029.399118026482, 21170.57658846845, 21176.258830940842, 21204.83373414446, 21213.681794772783, 896.8385690575759, 907.5371560504059, 915.7435917942987, 968.9244938118683, 989.4805227255605, 10390.636327728493, 10851.240413171003, 10895.564177432972, 10916.235863489639, 10918.945405272923, 585622.0, 600212.0, 600593.0, 614871.0, 628443.0, 100299.99700003052, 131556.3422182883, 134692.65307346996, 136638.0, 140380.0, 868.6655637579578, 880.8282384935004, 939.0, 1003.7691330994058, 1022.6880801355509, 18758.906205468884, 19342.129604168127, 19392.612147756685, 19638.410847674593, 19672.803271967095, 43968.56031439645, 55294.0, 56605.71697141488, 57202.0, 57418.0, 75497.86753198596, 76926.0, 81018.0, 81796.0024397304, 83226.0, 7008.649567521706, 7981.840363192588, 8071.757847264592, 8216.137305582828, 8239.134890836376, 7092.25531319204, 9316.0, 10869.836952445561, 12385.266062751401, 12617.810732839198, 10503.737406564896, 10920.890791091988, 11040.944795275973, 11307.830382544103, 11311.490982905625, 10671.89328106709, 10710.303830251009, 10801.24391292602, 10895.346279223271, 10954.233203675667, 9272.953635231781, 11096.944515277371, 11231.101511879193, 11293.661190164308, 11504.25222360544, 7905.169957154416, 7996.32031277348, 8017.198280171936, 8141.470804397695, 8179.591020449073, 8507.702230421906, 10800.783984320113, 10845.186611004048, 11030.50362733695, 11287.097032237565], "value": 9173.434068559964, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.726007538417, 1351.1082685427616, 1458.67179884526, 1550.4650895441068, 1609.7182992976227, 7434.14507331657, 7783.532988020723, 7902.565358905258, 8232.876506852403, 8237.464564803284, 8080.878786818203, 8091.595420228994, 8181.631826567804, 8194.877076843852, 8231.300339471152, 2303.8617682939034, 2332.790048895599, 2333.6499525071204, 2336.1122773346187, 2341.2039906431896, 2317.29322556621, 2359.752226016269, 2370.0519792083132, 2373.7982271506944, 2375.2636682628354, 2306.331163962449, 2307.9192228272013, 2342.7657234276608, 2344.6483027545833, 2344.7069116360453, 1947.191915355126, 2259.48031952651, 2286.1769762885347, 2315.0045480443405, 2334.568104900599, 1911.9426417207505, 2270.0465804362207, 2326.4881726020417, 2330.4989427273217, 2337.637666161748, 8230.547319897403, 8308.584570771438, 8316.625751841182, 8318.209770071833, 8329.875051874227, 5021.447640759514, 5207.609429292818, 5219.973900130475, 5247.895042099175, 5251.632385733, 3220.7584431167757, 3424.8116353600544, 3483.6690514401344, 3573.749837511375, 3578.8568457261676, 919.3059240273617, 1049.3756215052108, 1052.9578816847318, 1093.327603523835, 1100.5927806711488, 7982.640781164861, 10695.572177112907, 10770.838437423445, 10875.619353322674, 10875.945620271848, 4230.942264433892, 4356.586124318184, 4431.268840641258, 4446.088551846864, 4470.2177119004045, 8605.139486051345, 8608.612612432456, 8611.784705382415, 8640.049594544595, 8662.6101825418, 4248.746619747182, 4999.850004499871, 5070.416902056237, 5087.338645976073, 5098.0, 4051.716379853382, 4270.914581708411, 4427.070315233773, 4429.822807087745, 4693.20215563355, 3110.0, 3658.902329301199, 3675.46705727668, 3759.586445490994, 3760.360738674432, 1140.845985791924, 1142.6914733022038, 1143.1369316166408, 1152.475623591264, 1153.0602558914459, 772.884067389896, 853.9018012928468, 879.907609700985, 898.9460632361948, 913.1325241021057, 5298.682079075268, 5451.0, 5492.588055895823, 5676.637606974296, 5739.713014349266, 806.5604245686116, 900.4192295969194, 912.8402529557301, 931.2875650127658, 964.840801267797, 3169.904902852872, 3494.8427320770625, 3730.2539492101687, 3795.1650636859845, 3848.9037774055314, 9738.853917191247, 10221.335613185267, 10227.488625568692, 10238.283320167666, 10286.074253317238, 10151.086402223687, 10160.237982151368, 10181.33821301613, 10189.98100189975, 10207.132393746466, 7564.281393267631, 7704.499207551491, 7884.29041386278, 7893.842123157505, 7936.246056624612, 4811.422629284508, 5465.836024919259, 5512.310961129859, 5579.744557474698, 5594.468525490072, 9248.583813728264, 9335.346525743133, 9452.480113593818, 9461.574229159842, 9476.905230947603, 8599.484030958163, 8919.821603568022, 8969.955150224207, 9450.52747362643, 9476.668316608886, 6625.436837868835, 6773.0, 7043.788686339213, 7080.150381954199, 7100.573965561876, 7534.585597792174, 7701.1913749057785, 7820.648070836713, 7821.100573434128, 7824.64789084504, 6977.895331569929, 6982.197047339621, 7023.859522809617, 7149.0706208192605, 7162.713491460491, 6233.317004408694, 6830.45356371499, 6898.448124149955, 6900.861982760216, 6926.0, 6236.126942228001, 6563.507736919654, 6624.867502650017, 6626.237982632059, 6643.136392268974, 4623.953760462352, 4692.7888245029735, 4780.306855506002, 4783.497732738012, 4825.710457372569, 4667.299905014275, 4748.453927798348, 4772.880677983078, 4866.416030076271, 4885.120678277802], "value": 4310.9158032737905, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.45582291240413, 289.16719846841096, 304.7881722203068, 307.21812985950754, 311.7194524927564, 4761.69049011814, 5083.389993200821, 5092.516210959958, 5108.948910510894, 5110.540051395374, 3828.942565861514, 4556.75972631474, 4780.8565743027675, 4849.442314133876, 4962.6526143169995, 3180.4593219152684, 3287.8849240276536, 3714.6099659535766, 3813.199228162089, 3867.911327882954, 3230.78999865008, 3888.4834914383273, 3962.5839286874893, 3966.0, 3997.820098095578, 3216.388886111631, 3766.7551609145453, 3900.395438707005, 3915.353966595508, 3930.3296099157888, 3908.609139086069, 3909.8045097745, 3940.349842276021, 3955.3473676843287, 3957.683385329167, 2838.9432211355866, 3457.9654203458144, 3458.9968909016357, 3463.7748546344405, 3482.355764183634, 4927.51160663866, 4935.842045062918, 4968.677035992649, 4975.825846095369, 4981.67985483847, 3766.7174961877695, 4304.7632380219075, 4440.622547083502, 4466.865994020184, 4534.614557762594, 4608.9308660370125, 4859.514048595148, 4899.828506002308, 4984.2025275955675, 5328.733563321857, 3490.947635785465, 3794.0704527390967, 3808.067023579241, 3818.4845045919, 3824.560175579816, 5090.643654944192, 5290.55030322423, 5545.975718862586, 5626.9437305626825, 5649.0, 3831.5593706723525, 3851.7496362736333, 3865.16898866745, 3876.0, 3902.726809123334, 3435.347284016029, 3811.3711237646035, 3925.156091440297, 3953.367461206193, 3959.0, 4215.747055176699, 4283.714885534389, 4480.462344518679, 4500.482444518857, 4502.347159661831, 4299.54854740254, 5339.385970613352, 5345.251664766935, 5391.32608423947, 5449.972750136224, 1024.948752562369, 1140.8288756686397, 1184.6919800851667, 1227.5642147037852, 1239.0397441982536, 653.6013032050446, 685.0546246180239, 782.159178882692, 794.7456813819638, 825.6871574197055, 4704.717716936994, 4727.669063165546, 4735.697683137165, 4767.856964291077, 4773.618110551148, 4449.731826429452, 4819.542143496433, 4926.778294976734, 5193.636445448858, 5195.584353251731, 3713.5543734752005, 4495.269321311253, 4626.4910859805395, 4728.770519664843, 4783.736894470838, 2265.8361264991895, 2530.7975361971, 2541.567933451318, 2563.231709753462, 2679.5310820606314, 4090.141070375195, 4480.865574032784, 4590.104929538775, 5244.659097158673, 5308.23030660552, 4593.012502312019, 5035.370578677665, 5194.0, 5249.921251181236, 5309.5752339812725, 4271.572842715704, 5309.2301616266195, 5336.4129945705945, 5351.50157955773, 5359.732013399314, 4527.796249168729, 5097.490250974873, 5109.77365432301, 5117.718525480986, 5193.715184972489, 4762.59517940965, 4800.5199480051715, 5080.441151473261, 5174.663646862942, 5255.395629502656, 5106.259592359162, 5230.921536177037, 5350.7324633767375, 5388.141091323487, 5388.841399099212, 3410.1414728973264, 3528.8764893228613, 3531.947020794639, 3550.2012047289154, 3567.732420068557, 4153.91692166161, 4419.381286619811, 4845.248986407112, 5008.84973450797, 5217.478252174752, 4310.069152976006, 4512.571305726082, 4579.061292434976, 4872.634552408655, 4964.702117872939, 4573.748443835621, 4844.467108617979, 4989.301497790239, 4990.975045124751, 5002.4222991365095, 4354.738715677069, 4603.250764709389, 4870.514493079645, 4878.121938051185, 4978.730423741923, 4307.590778876001, 4771.727241398205, 5054.898902021865, 5224.290753565011, 5240.4759524047295, 5211.661242019257, 5212.869678258074, 5219.642892847811, 5229.555487783581, 5300.840974770763], "value": 3772.413671318433, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.5662032919261, 698.9161300643924, 754.1967804288432, 759.2331744937618, 785.261853857374, 14145.222012789292, 14602.342894569743, 14642.048266862646, 14646.121232726031, 14646.267686615667, 12830.101892867502, 12884.226946383224, 12894.4197511112, 12927.870721292784, 12982.156159849626, 638.6487431912453, 656.1339032477132, 660.851308455596, 790.182161462886, 803.5379656697393, 819.5779173725506, 821.1583127294512, 829.1501211258447, 831.0609011816639, 832.6835802395082, 873.7640836974014, 875.4703404440318, 876.1588874680315, 876.4040452492303, 877.3945977275691, 665.7969319357594, 690.3096903096903, 869.5608717597604, 870.2776695342882, 872.8167084912176, 511.74924287099424, 512.2623422271917, 512.6155383462403, 609.8719268953525, 626.1265534579236, 13498.122622029538, 13925.442982280696, 13985.580432586938, 14012.859871401257, 14014.089084209494, 13041.608751737464, 13133.540326088636, 13224.86775132246, 13298.667533311647, 13483.460661573525, 11566.0, 11889.643310700692, 11912.583059592873, 12226.20529665569, 12324.260544367367, 4288.592583704543, 4344.152890186415, 4370.38814565961, 4503.549645035503, 4522.118186953546, 89595.0, 90889.54555227181, 95161.0, 95198.09603807954, 95773.52113239389, 10392.948035259775, 10412.843807342895, 10462.633807816843, 10503.0, 10580.788384232272, 19971.60056798856, 20313.390598282076, 20378.675386099858, 20554.58890822175, 20917.37247882566, 7501.699932002659, 7565.146254838576, 7605.23947605235, 7755.612219389008, 7755.728549500854, 4047.514298284225, 4292.656587472995, 4301.333293339538, 4337.200061974246, 4381.233284175257, 3223.1458663454205, 3302.8678852846097, 3653.12325041988, 3829.2341531693774, 3833.559140698855, 668.7224801707237, 677.4817264792438, 688.5696439725172, 688.8760023195773, 689.8551304226069, 583.0554501707177, 596.7344531683447, 646.7380710812129, 649.3636236488268, 676.7665155521337, 8948.284137269004, 9112.952010518742, 9147.54262286883, 9382.157866432974, 9385.67150149755, 652.3476523476523, 655.5574986883835, 656.7931101703044, 666.1207206487487, 677.4580335731396, 4242.17277630859, 4602.654800889946, 4618.284165954283, 4619.953800461953, 4656.8137274508645, 89915.55042224878, 93288.0, 115003.0, 120432.0, 122049.0, 97555.0, 100222.0, 101974.0, 102902.0, 108659.45670271598, 58411.4158858406, 59456.70271648614, 66361.01375438117, 88807.55596221978, 91282.54358728297, 8775.42959707617, 8795.164459376349, 8898.06570310134, 9026.954865225633, 9070.63717451321, 55770.72114639401, 56592.43407566036, 57396.71301643465, 57916.0, 59890.70054649699, 67005.66497167484, 67962.0, 82031.0, 82326.58836705778, 83178.0, 11143.442827858737, 11300.321980681185, 11380.487878045342, 11410.201285909907, 11812.70468238301, 13228.338583071001, 13448.663783405493, 13845.86154138486, 14007.509737159151, 14028.088174269049, 12793.360331983178, 12825.08656483735, 12854.935725321313, 13335.33308336458, 13353.265570393722, 10527.420991845522, 12454.688632784253, 12492.562760303346, 13186.472541098248, 13485.325733713078, 12029.939850300692, 12819.551315703906, 13072.934635326763, 13202.0, 13573.04988650785, 9909.504524773876, 10218.846717299395, 10550.261481696207, 10599.470026498799, 11178.496967636313, 10313.587456501657, 11311.377874216998, 11328.660140195807, 11370.08360795509, 11447.484863181011], "value": 7268.782676247436, "unit": "score"}], "name": "dromaeo_css", "value": 6964.819795826369, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 113.0, 116.0, 114.0, 113.0, 115.0, 114.0, 111.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 141.0, 141.0, 146.0, 111.0, 111.0, 110.0, 109.0, 141.0, 109.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 186.0, 187.0, 186.0, 187.0, 184.0, 185.0, 187.0, 186.0], "value": 186.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 59.0, 89.0, 61.0, 60.0, 59.0, 58.0, 82.0, 59.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 95.0, 70.0, 67.0, 69.0, 89.0, 67.0, 69.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 86.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 108.0, 148.0, 96.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 117.0, 78.0, 79.0, 82.0, 80.0, 79.0, 112.0, 128.0, 97.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 63.0, 55.0, 54.0, 57.0, 58.0, 58.0, 55.0, 57.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 76.0, 75.0, 73.0, 71.0, 72.0, 72.0, 72.0, 72.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 92.0, 103.0, 80.0, 82.0, 103.0, 102.0, 103.0, 78.0, 94.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 139.0, 142.0, 156.0, 153.0, 136.0, 142.0, 144.0, 190.0, 149.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 185.0, 169.0, 167.0, 164.0, 164.0, 166.0, 164.0, 164.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 68.0, 67.0, 67.0, 69.0, 81.0, 83.0, 87.0], "value": 72.7, "unit": "score"}], "name": "kraken", "value": 1464.6999999999998, "alertThreshold": 2.0}]} 02:57:01 INFO - Return code: 0 02:57:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:57:01 INFO - # TBPL SUCCESS # 02:57:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:57:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:57:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:57:01 INFO - 2017-01-08 02:57:01.740 screenresolution[2306:26830] starting screenresolution argv=screenresolution get 02:57:01 INFO - 2017-01-08 02:57:01.743 screenresolution[2306:26830] Display 0: 1600x1200x32@60 02:57:01 INFO - 2017-01-08 02:57:01.750 screenresolution[2307:26833] starting screenresolution argv=screenresolution list 02:57:01 INFO - Available Modes on Display 0 02:57:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:57:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:57:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:57:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:57:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:57:01 INFO - Intel Iris: 02:57:01 INFO - Chipset Model: Intel Iris 02:57:01 INFO - Type: GPU 02:57:01 INFO - Bus: Built-In 02:57:01 INFO - VRAM (Dynamic, Max): 1536 MB 02:57:01 INFO - Vendor: Intel (0x8086) 02:57:01 INFO - Device ID: 0x0a2e 02:57:01 INFO - Revision ID: 0x0009 02:57:01 INFO - Displays: 02:57:01 INFO - Display: 02:57:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:57:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:57:01 INFO - Display Serial Number: 1600x1200 60 02:57:01 INFO - Main Display: Yes 02:57:01 INFO - Mirror: Off 02:57:01 INFO - Online: Yes 02:57:01 INFO - Rotation: Supported 02:57:02 INFO - Return code: 0 02:57:02 INFO - Running post-action listener: _resource_record_post_action 02:57:02 INFO - [mozharness: 2017-01-08 10:57:02.000524Z] Finished run-tests step (success) 02:57:02 INFO - Running post-run listener: _resource_record_post_run 02:57:02 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 376832; Write bytes: 661281792; Read time: 6; Write time: 1926 02:57:02 INFO - TinderboxPrint: CPU usage
53.4% 02:57:02 INFO - TinderboxPrint: I/O read bytes / time
376,832 / 6 02:57:02 INFO - TinderboxPrint: I/O write bytes / time
661,281,792 / 1,926 02:57:02 INFO - TinderboxPrint: CPU idle
2,260.5 (46.6%) 02:57:02 INFO - TinderboxPrint: CPU system
975.2 (20.1%) 02:57:02 INFO - TinderboxPrint: CPU user
1,618.0 (33.3%) 02:57:02 INFO - TinderboxPrint: Swap in / out
349,310,976 / 0 02:57:02 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344076800; Write bytes: 345290752; Read time: 26777; Write time: 726 02:57:02 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 376832; Write bytes: 307946496; Read time: 6; Write time: 1165 02:57:02 INFO - Running post-run listener: _upload_blobber_files 02:57:02 INFO - Blob upload gear active. 02:57:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:57:02 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 02:57:02 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'] 02:57:02 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 02:57:02 INFO - (blobuploader) - INFO - Open directory for files ... 02:57:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:57:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:57:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:03 INFO - (blobuploader) - INFO - Done attempting. 02:57:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:57:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:57:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:04 INFO - (blobuploader) - INFO - Done attempting. 02:57:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:57:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:57:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:07 INFO - (blobuploader) - INFO - Done attempting. 02:57:07 INFO - (blobuploader) - INFO - Iteration through files over. 02:57:07 INFO - Return code: 0 02:57:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:57:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:57:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1006a80e2a2f148ba6740f958cfa37a05da180ad2214d066d7b752a6b4441ec3b8e1cb786553aafa4d89cee85271ff5ac78d87c982356aa63434ba45002cad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db8795bd867e0f0c0c3b072b00a11cdc8061afaceeb9e52d1870a7ec454b2a9d571ab30203489b54dbb2b9ed6172246d1845054c30e0928a26515ae3617d6771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a62cdc70961b40968a17bef458b7ca565660ea8cc5610ee08fd7909fb654e1319cb8414988033d0deb150ecd6b32af141473977270d922b65995dbcec11ecf2"} 02:57:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:57:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:57:07 INFO - Contents: 02:57:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1006a80e2a2f148ba6740f958cfa37a05da180ad2214d066d7b752a6b4441ec3b8e1cb786553aafa4d89cee85271ff5ac78d87c982356aa63434ba45002cad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db8795bd867e0f0c0c3b072b00a11cdc8061afaceeb9e52d1870a7ec454b2a9d571ab30203489b54dbb2b9ed6172246d1845054c30e0928a26515ae3617d6771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a62cdc70961b40968a17bef458b7ca565660ea8cc5610ee08fd7909fb654e1319cb8414988033d0deb150ecd6b32af141473977270d922b65995dbcec11ecf2"} 02:57:07 INFO - Running post-run listener: copy_logs_to_upload_dir 02:57:07 INFO - Copying logs to upload dir... 02:57:07 INFO - mkdir: /builds/slave/test/build/upload/logs 02:57:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.215141 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-08 02:57:07.248360) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-08 02:57:07.252216) ========= 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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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/f1006a80e2a2f148ba6740f958cfa37a05da180ad2214d066d7b752a6b4441ec3b8e1cb786553aafa4d89cee85271ff5ac78d87c982356aa63434ba45002cad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db8795bd867e0f0c0c3b072b00a11cdc8061afaceeb9e52d1870a7ec454b2a9d571ab30203489b54dbb2b9ed6172246d1845054c30e0928a26515ae3617d6771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a62cdc70961b40968a17bef458b7ca565660ea8cc5610ee08fd7909fb654e1319cb8414988033d0deb150ecd6b32af141473977270d922b65995dbcec11ecf2"} build_url:https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/PeEvaw_tSJ-mJwCA-D15wg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f1006a80e2a2f148ba6740f958cfa37a05da180ad2214d066d7b752a6b4441ec3b8e1cb786553aafa4d89cee85271ff5ac78d87c982356aa63434ba45002cad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/db8795bd867e0f0c0c3b072b00a11cdc8061afaceeb9e52d1870a7ec454b2a9d571ab30203489b54dbb2b9ed6172246d1845054c30e0928a26515ae3617d6771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a62cdc70961b40968a17bef458b7ca565660ea8cc5610ee08fd7909fb654e1319cb8414988033d0deb150ecd6b32af141473977270d922b65995dbcec11ecf2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-08 02:57:07.291602) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:57:07.292397) ========= 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.ugSFYS29tU/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.yoxVm5LV1Q/Listeners TMPDIR=/var/folders/hy/45ssh3ld6sx_27c31ssq2l9w00000w/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.004971 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-08 02:57:07.344528) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-08 02:57:07.345325) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-08 02:57:07.346041) ========= ========= Total master_lag: 0.22 =========