builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0159 starttime: 1476043677.74 results: success (0) buildid: 20161009120417 builduid: 990b1eb17c11407ea4d82ffe76ab65cc revision: 35697c37c23bdd2c2c30a06a802cf16c3c9ce037 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:57.738740) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:57.739170) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:57.739442) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:57.764032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:57.764296) ========= 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.xLTOLbPiQu/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.U9911NIOAt/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-09 13:07:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.20M=0.001s 2016-10-09 13:07:57 (8.20 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124740 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:57.910985) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:57.914293) ========= 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.xLTOLbPiQu/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.U9911NIOAt/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.028268 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:58.008517) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-09 13:07:58.008852) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 35697c37c23bdd2c2c30a06a802cf16c3c9ce037 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 35697c37c23bdd2c2c30a06a802cf16c3c9ce037 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xLTOLbPiQu/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.U9911NIOAt/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-09 13:07:58,099 truncating revision to first 12 chars 2016-10-09 13:07:58,099 Setting DEBUG logging. 2016-10-09 13:07:58,099 attempt 1/10 2016-10-09 13:07:58,100 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/35697c37c23b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-09 13:07:58,877 unpacking tar archive at: mozilla-central-35697c37c23b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.042265 ========= master_lag: 0.84 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-09 13:07:59.889024) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:59.889300) ========= script_repo_revision: 35697c37c23bdd2c2c30a06a802cf16c3c9ce037 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:59.889647) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-09 13:07:59.889906) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-09 13:08:00.225809) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-09 13:08:00.226076) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xLTOLbPiQu/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.U9911NIOAt/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08:00 INFO - MultiFileLogger online at 20161009 13:08:00 in /builds/slave/test 13:08:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:08:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:08:00 INFO - {'append_to_log': False, 13:08:00 INFO - 'base_work_dir': '/builds/slave/test', 13:08:00 INFO - 'blob_upload_branch': 'Firefox', 13:08:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:08:00 INFO - 'branch': 'Firefox', 13:08:00 INFO - 'buildbot_json_path': 'buildprops.json', 13:08:00 INFO - 'config_files': ('talos/mac_config.py',), 13:08:00 INFO - 'default_actions': ('clobber', 13:08:00 INFO - 'read-buildbot-config', 13:08:00 INFO - 'download-and-extract', 13:08:00 INFO - 'populate-webroot', 13:08:00 INFO - 'create-virtualenv', 13:08:00 INFO - 'install', 13:08:00 INFO - 'run-tests'), 13:08:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:08:00 INFO - 'download_minidump_stackwalk': True, 13:08:00 INFO - 'download_symbols': 'ondemand', 13:08:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:08:00 INFO - 'tooltool.py': '/tools/tooltool.py', 13:08:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:08:00 INFO - '/tools/misc-python/virtualenv.py')}, 13:08:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:08:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:08:00 INFO - 'installer_path': 'installer.exe', 13:08:00 INFO - 'log_level': 'info', 13:08:00 INFO - 'log_name': 'talos', 13:08:00 INFO - 'log_to_console': True, 13:08:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:08:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:08:00 INFO - 'opt_config_files': (), 13:08:00 INFO - 'pip_index': False, 13:08:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:08:00 INFO - 'cmd': ('bash', 13:08:00 INFO - '-c', 13:08:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:08:00 INFO - 'enabled': True, 13:08:00 INFO - 'halt_on_failure': False, 13:08:00 INFO - 'name': 'check_screen_resolution'},), 13:08:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:08:00 INFO - 'cmd': ('bash', 13:08:00 INFO - '-c', 13:08:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:08:00 INFO - 'enabled': True, 13:08:00 INFO - 'halt_on_failure': False, 13:08:00 INFO - 'name': 'check_screen_resolution'},), 13:08:00 INFO - 'run_cmd_checks_enabled': True, 13:08:00 INFO - 'sps_profile': False, 13:08:00 INFO - 'sps_profile_interval': 0, 13:08:00 INFO - 'suite': 'dromaeojs', 13:08:00 INFO - 'system_bits': '32', 13:08:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:08:00 INFO - 'title': 't-yosemite-r7-0159', 13:08:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:08:00 INFO - 'use_talos_json': True, 13:08:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:08:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:08:00 INFO - 'work_dir': 'build'} 13:08:00 INFO - [mozharness: 2016-10-09 20:08:00.615245Z] Running clobber step. 13:08:00 INFO - Running pre-action listener: _resource_record_pre_action 13:08:00 INFO - Running main action method: clobber 13:08:00 INFO - rmtree: /builds/slave/test/build 13:08:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:08:02 INFO - Running post-action listener: _resource_record_post_action 13:08:02 INFO - [mozharness: 2016-10-09 20:08:02.840393Z] Finished clobber step (success) 13:08:02 INFO - [mozharness: 2016-10-09 20:08:02.840583Z] Running read-buildbot-config step. 13:08:02 INFO - Running pre-action listener: _resource_record_pre_action 13:08:02 INFO - Running main action method: read_buildbot_config 13:08:02 INFO - Using buildbot properties: 13:08:02 INFO - { 13:08:02 INFO - "project": "", 13:08:02 INFO - "product": "firefox", 13:08:02 INFO - "script_repo_revision": "production", 13:08:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 13:08:02 INFO - "repository": "", 13:08:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 13:08:02 INFO - "buildid": "20161009120417", 13:08:02 INFO - "pgo_build": "False", 13:08:02 INFO - "basedir": "/builds/slave/test", 13:08:02 INFO - "buildnumber": 1, 13:08:02 INFO - "slavename": "t-yosemite-r7-0159", 13:08:02 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 13:08:02 INFO - "platform": "yosemite_r7", 13:08:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:08:02 INFO - "branch": "mozilla-central", 13:08:02 INFO - "repo_path": "mozilla-central", 13:08:02 INFO - "revision": "35697c37c23bdd2c2c30a06a802cf16c3c9ce037", 13:08:02 INFO - "stage_platform": "macosx64", 13:08:02 INFO - "builduid": "990b1eb17c11407ea4d82ffe76ab65cc", 13:08:02 INFO - "slavebuilddir": "test" 13:08:02 INFO - } 13:08:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 13:08:02 INFO - Running post-action listener: _resource_record_post_action 13:08:02 INFO - [mozharness: 2016-10-09 20:08:02.844093Z] Finished read-buildbot-config step (success) 13:08:02 INFO - [mozharness: 2016-10-09 20:08:02.844230Z] Running download-and-extract step. 13:08:02 INFO - Running pre-action listener: _resource_record_pre_action 13:08:02 INFO - Running main action method: download_and_extract 13:08:02 INFO - mkdir: /builds/slave/test/build/tests 13:08:02 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')]} 13:08:02 INFO - trying https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 13:08:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 13:08:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 13:08:04 INFO - Downloaded 1199 bytes. 13:08:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 13:08:04 INFO - Using the following test package requirements: 13:08:04 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:08:04 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:08:04 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 13:08:04 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:08:04 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 13:08:04 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:08:04 INFO - u'jsshell-mac.zip'], 13:08:04 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:08:04 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 13:08:04 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:08:04 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:08:04 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 13:08:04 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:08:04 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 13:08:04 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:08:04 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 13:08:04 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:08:04 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 13:08:04 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:08:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:08:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:08:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:08:04 INFO - Http code: 200 13:08:04 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 13:08:04 INFO - x-amz-cf-id: e7FMFPwDrL44gXR604ryMZbVFdGmcWCtGJeKcx-woyeewjdcWuL4cA== 13:08:04 INFO - x-amz-version-id: d_St8uI3qu6Zz4ozjbBn86e4Fh09dyGL 13:08:05 INFO - Expected file size: 16504474 13:08:05 INFO - Obtained file size: 16504474 13:08:11 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:08:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:08:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:08:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:08:12 INFO - Http code: 200 13:08:12 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 13:08:12 INFO - x-amz-cf-id: DwrvI7BFWrv_VqCZw-dFDHx6LEXiD_K_OT0rLQf0CK2XzZS53bj-9w== 13:08:12 INFO - x-amz-version-id: d_St8uI3qu6Zz4ozjbBn86e4Fh09dyGL 13:08:13 INFO - Expected file size: 16504474 13:08:13 INFO - Obtained file size: 16504474 13:08:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 13:08:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:08:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 13:08:19 INFO - Http code: 200 13:08:19 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 13:08:19 INFO - x-amz-cf-id: rkkNHJk0HmH3mg_h3IzI68rfFHk_sgNFV00V-YprelT5b2NBA-Ez3Q== 13:08:19 INFO - x-amz-version-id: 7CuJd89tU71vYj4PKI5maX184Em6U9EF 13:08:20 INFO - Expected file size: 14409006 13:08:20 INFO - Obtained file size: 14409006 13:08: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')]} 13:08:20 INFO - trying https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:08:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 13:08:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 13:08:23 INFO - Downloaded 111714562 bytes. 13:08:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:08:23 INFO - mkdir: /builds/slave/test/properties 13:08:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:08:23 INFO - Writing to file /builds/slave/test/properties/build_url 13:08:23 INFO - Contents: 13:08:23 INFO - build_url:https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:08:24 INFO - Running post-action listener: _resource_record_post_action 13:08:24 INFO - Running post-action listener: set_extra_try_arguments 13:08:24 INFO - [mozharness: 2016-10-09 20:08:24.058366Z] Finished download-and-extract step (success) 13:08:24 INFO - [mozharness: 2016-10-09 20:08:24.058514Z] Running populate-webroot step. 13:08:24 INFO - Running pre-action listener: _resource_record_pre_action 13:08:24 INFO - Running main action method: populate_webroot 13:08:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:08:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:08:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:08:24 INFO - '--tppagecycles', 13:08:24 INFO - '7'], 13:08:24 INFO - 'tests': ['tsvgm']}}, 13:08:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:08:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:08:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:08:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:08:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:08:24 INFO - 'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:08:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:08:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:08:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:08:24 INFO - 'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['damp', 'tps']}, 13:08:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:08:24 INFO - 'tests': ['damp', 'tps']}, 13:08:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['dromaeo_dom']}, 13:08:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:08:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['basic_compositor_video']}, 13:08:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:08:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['a11yr', 13:08:24 INFO - 'ts_paint', 13:08:24 INFO - 'tpaint', 13:08:24 INFO - 'sessionrestore', 13:08:24 INFO - 'sessionrestore_no_auto_restore', 13:08:24 INFO - 'tabpaint']}, 13:08:24 INFO - 'other-e10s': {'tests': ['a11yr', 13:08:24 INFO - 'ts_paint', 13:08:24 INFO - 'tpaint', 13:08:24 INFO - 'sessionrestore', 13:08:24 INFO - 'sessionrestore_no_auto_restore', 13:08:24 INFO - 'tabpaint']}, 13:08:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:08:24 INFO - 'ts_paint', 13:08:24 INFO - 'tpaint', 13:08:24 INFO - 'sessionrestore', 13:08:24 INFO - 'sessionrestore_no_auto_restore', 13:08:24 INFO - 'tabpaint']}, 13:08:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:08:24 INFO - 'ts_paint', 13:08:24 INFO - 'tpaint', 13:08:24 INFO - 'sessionrestore', 13:08:24 INFO - 'sessionrestore_no_auto_restore', 13:08:24 INFO - 'tabpaint']}, 13:08:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['a11yr', 13:08:24 INFO - 'ts_paint', 13:08:24 INFO - 'tpaint', 13:08:24 INFO - 'sessionrestore', 13:08:24 INFO - 'sessionrestore_no_auto_restore', 13:08:24 INFO - 'tabpaint']}, 13:08:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['a11yr', 13:08:24 INFO - 'ts_paint', 13:08:24 INFO - 'tpaint', 13:08:24 INFO - 'sessionrestore', 13:08:24 INFO - 'sessionrestore_no_auto_restore', 13:08:24 INFO - 'tabpaint']}, 13:08:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['tsvgx', 13:08:24 INFO - 'tsvgr_opacity', 13:08:24 INFO - 'tart', 13:08:24 INFO - 'tscrollx', 13:08:24 INFO - 'cart']}, 13:08:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:08:24 INFO - 'tsvgr_opacity', 13:08:24 INFO - 'tart', 13:08:24 INFO - 'tscrollx', 13:08:24 INFO - 'cart']}, 13:08:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:08:24 INFO - 'talos_options': ['--disable-e10s'], 13:08:24 INFO - 'tests': ['tp5o']}, 13:08:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:08:24 INFO - 'tests': ['tp5o']}, 13:08:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:08:24 INFO - 'talos_options': ['--disable-e10s', 13:08:24 INFO - '--xperf_path', 13:08:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:08:24 INFO - 'tests': ['tp5n']}, 13:08:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:08:24 INFO - 'talos_options': ['--xperf_path', 13:08:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:08:24 INFO - 'tests': ['tp5n']}}, 13:08:24 INFO - 'talos.zip': {'path': '', 13:08:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:08:24 INFO - Running post-action listener: _resource_record_post_action 13:08:24 INFO - [mozharness: 2016-10-09 20:08:24.077735Z] Finished populate-webroot step (success) 13:08:24 INFO - [mozharness: 2016-10-09 20:08:24.077851Z] Running create-virtualenv step. 13:08:24 INFO - Running pre-action listener: _resource_record_pre_action 13:08:24 INFO - Running main action method: create_virtualenv 13:08:24 INFO - Creating virtualenv /builds/slave/test/build/venv 13:08:24 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 13:08:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:08:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:08:24 INFO - Using real prefix '/tools/python27' 13:08:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:08:24 INFO - Installing distribute.............................................................................................................................................................................................done. 13:08:27 INFO - Installing pip.................done. 13:08:27 INFO - Return code: 0 13:08:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:08:28 INFO - Reading from file tmpfile_stdout 13:08:28 INFO - Output received: 13:08:28 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) 13:08:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:08: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')]} 13:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08: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')]} 13:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08: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', 'pip>=1.5'] in /builds/slave/test/build 13:08: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 pip>=1.5 13:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:28 INFO - 'HOME': '/Users/cltbld', 13:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:28 INFO - 'LOGNAME': 'cltbld', 13:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:28 INFO - 'MOZ_NO_REMOTE': '1', 13:08:28 INFO - 'NO_EM_RESTART': '1', 13:08:28 INFO - 'PAGER': '/bin/cat', 13:08:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:28 INFO - 'PWD': '/builds/slave/test', 13:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:28 INFO - 'SHELL': '/bin/bash', 13:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:28 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:28 INFO - 'USER': 'cltbld', 13:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:28 INFO - 'XPC_FLAGS': '0x0', 13:08:28 INFO - 'XPC_SERVICE_NAME': '0', 13:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:28 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 13:08:28 INFO - Cleaning up... 13:08:28 INFO - Return code: 0 13:08:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:08: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')]} 13:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08: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')]} 13:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08: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', '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 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08: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', 'psutil>=3.1.1'] in /builds/slave/test/build 13:08: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 psutil>=3.1.1 13:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:28 INFO - 'HOME': '/Users/cltbld', 13:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:28 INFO - 'LOGNAME': 'cltbld', 13:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:28 INFO - 'MOZ_NO_REMOTE': '1', 13:08:28 INFO - 'NO_EM_RESTART': '1', 13:08:28 INFO - 'PAGER': '/bin/cat', 13:08:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:28 INFO - 'PWD': '/builds/slave/test', 13:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:28 INFO - 'SHELL': '/bin/bash', 13:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:28 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:28 INFO - 'USER': 'cltbld', 13:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:28 INFO - 'XPC_FLAGS': '0x0', 13:08:28 INFO - 'XPC_SERVICE_NAME': '0', 13:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:28 INFO - Downloading/unpacking psutil>=3.1.1 13:08:28 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 13:08:28 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 13:08:28 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 13:08:28 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 13:08:28 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 13:08:28 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 13:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:08:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:08:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:08:32 INFO - Installing collected packages: psutil 13:08:32 INFO - Running setup.py install for psutil 13:08:32 INFO - building 'psutil._psutil_osx' extension 13:08:32 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 13:08:33 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 13:08:33 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 13:08:33 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 13:08:33 INFO - building 'psutil._psutil_posix' extension 13:08:33 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 13:08:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:08:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:08:33 INFO - ^ 13:08:33 INFO - 1 warning generated. 13:08:33 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 13:08:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:08:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:08:33 INFO - Successfully installed psutil 13:08:33 INFO - Cleaning up... 13:08:33 INFO - Return code: 0 13:08:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:08:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:08:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:33 INFO - 'HOME': '/Users/cltbld', 13:08:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:33 INFO - 'LOGNAME': 'cltbld', 13:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:33 INFO - 'MOZ_NO_REMOTE': '1', 13:08:33 INFO - 'NO_EM_RESTART': '1', 13:08:33 INFO - 'PAGER': '/bin/cat', 13:08:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:33 INFO - 'PWD': '/builds/slave/test', 13:08:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:33 INFO - 'SHELL': '/bin/bash', 13:08:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:33 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:33 INFO - 'USER': 'cltbld', 13:08:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:33 INFO - 'XPC_FLAGS': '0x0', 13:08:33 INFO - 'XPC_SERVICE_NAME': '0', 13:08:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:08:34 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 13:08:34 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 13:08:34 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 13:08:34 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 13:08:34 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 13:08:34 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 13:08:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:08:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:08:36 INFO - Installing collected packages: mozsystemmonitor 13:08:36 INFO - Running setup.py install for mozsystemmonitor 13:08:36 INFO - Successfully installed mozsystemmonitor 13:08:36 INFO - Cleaning up... 13:08:36 INFO - Return code: 0 13:08:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:08:36 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')]} 13:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:36 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')]} 13:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:36 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 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08:36 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 13:08:36 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 13:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:36 INFO - 'HOME': '/Users/cltbld', 13:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:36 INFO - 'LOGNAME': 'cltbld', 13:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:36 INFO - 'MOZ_NO_REMOTE': '1', 13:08:36 INFO - 'NO_EM_RESTART': '1', 13:08:36 INFO - 'PAGER': '/bin/cat', 13:08:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:36 INFO - 'PWD': '/builds/slave/test', 13:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:36 INFO - 'SHELL': '/bin/bash', 13:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:36 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:36 INFO - 'USER': 'cltbld', 13:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:36 INFO - 'XPC_FLAGS': '0x0', 13:08:36 INFO - 'XPC_SERVICE_NAME': '0', 13:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:37 INFO - Downloading/unpacking jsonschema==2.5.1 13:08:37 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 13:08:37 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 13:08:37 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 13:08:37 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 13:08:37 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 13:08:37 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 13:08:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:08:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:08:39 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 13:08:39 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 13:08:39 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 13:08:39 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 13:08:39 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 13:08:39 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 13:08:39 INFO - Downloading functools32-3.2.3-2.tar.gz 13:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:08:40 INFO - warning: no files found matching '*.txt' 13:08:40 INFO - no previously-included directories found matching 'build' 13:08:40 INFO - no previously-included directories found matching 'dist' 13:08:40 INFO - no previously-included directories found matching '.git*' 13:08:40 INFO - Installing collected packages: jsonschema, functools32 13:08:40 INFO - Running setup.py install for functools32 13:08:40 INFO - warning: no files found matching '*.txt' 13:08:40 INFO - no previously-included directories found matching 'build' 13:08:40 INFO - no previously-included directories found matching 'dist' 13:08:40 INFO - no previously-included directories found matching '.git*' 13:08:40 INFO - Successfully installed jsonschema functools32 13:08:40 INFO - Cleaning up... 13:08:40 INFO - Return code: 0 13:08:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:40 INFO - 'HOME': '/Users/cltbld', 13:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:40 INFO - 'LOGNAME': 'cltbld', 13:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:40 INFO - 'MOZ_NO_REMOTE': '1', 13:08:40 INFO - 'NO_EM_RESTART': '1', 13:08:40 INFO - 'PAGER': '/bin/cat', 13:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:40 INFO - 'PWD': '/builds/slave/test', 13:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:40 INFO - 'SHELL': '/bin/bash', 13:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:40 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:40 INFO - 'USER': 'cltbld', 13:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:40 INFO - 'XPC_FLAGS': '0x0', 13:08:40 INFO - 'XPC_SERVICE_NAME': '0', 13:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:08:40 INFO - Cleaning up... 13:08:40 INFO - Return code: 0 13:08:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:40 INFO - 'HOME': '/Users/cltbld', 13:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:40 INFO - 'LOGNAME': 'cltbld', 13:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:40 INFO - 'MOZ_NO_REMOTE': '1', 13:08:40 INFO - 'NO_EM_RESTART': '1', 13:08:40 INFO - 'PAGER': '/bin/cat', 13:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:40 INFO - 'PWD': '/builds/slave/test', 13:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:40 INFO - 'SHELL': '/bin/bash', 13:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:40 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:40 INFO - 'USER': 'cltbld', 13:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:40 INFO - 'XPC_FLAGS': '0x0', 13:08:40 INFO - 'XPC_SERVICE_NAME': '0', 13:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:40 INFO - Downloading/unpacking blobuploader==1.2.4 13:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:08:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:08:43 INFO - Downloading blobuploader-1.2.4.tar.gz 13:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:08:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:08:43 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 13:08:43 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 13:08:43 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 13:08:43 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 13:08:43 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 13:08:43 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 13:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:08:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:08: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 13:08: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 13:08: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 13:08: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 13:08: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 13:08: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 13:08:44 INFO - Downloading docopt-0.6.1.tar.gz 13:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:08:44 INFO - Installing collected packages: blobuploader, requests, docopt 13:08:44 INFO - Running setup.py install for blobuploader 13:08:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:08:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:08:44 INFO - Running setup.py install for requests 13:08:45 INFO - Running setup.py install for docopt 13:08:45 INFO - Successfully installed blobuploader requests docopt 13:08:45 INFO - Cleaning up... 13:08:45 INFO - Return code: 0 13:08:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:08:45 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')]} 13:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:45 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')]} 13:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:45 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 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08:45 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 13:08:45 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 13:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:45 INFO - 'HOME': '/Users/cltbld', 13:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:45 INFO - 'LOGNAME': 'cltbld', 13:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:45 INFO - 'MOZ_NO_REMOTE': '1', 13:08:45 INFO - 'NO_EM_RESTART': '1', 13:08:45 INFO - 'PAGER': '/bin/cat', 13:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:45 INFO - 'PWD': '/builds/slave/test', 13:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:45 INFO - 'SHELL': '/bin/bash', 13:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:45 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:45 INFO - 'USER': 'cltbld', 13:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:45 INFO - 'XPC_FLAGS': '0x0', 13:08:45 INFO - 'XPC_SERVICE_NAME': '0', 13:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:08:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-U1b4oY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:08:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-shNlKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:08:45 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-VobUz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:08:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-qbDLRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:08:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-fnOuD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:08:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-AU5c_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:08:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-CUM9gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:08:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-oioRDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:08:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-sx65MV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:08:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-KTVHDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:08:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-bzOfyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:08:46 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-_muy7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:08:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-lhf2Lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:08:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-piOcn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-uOy8aC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:08:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-MB7mqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:08:47 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-fvJwE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:08:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:08:47 INFO - Running setup.py install for manifestparser 13:08:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:08:47 INFO - Running setup.py install for mozcrash 13:08:47 INFO - Running setup.py install for mozdebug 13:08:47 INFO - Running setup.py install for mozdevice 13:08:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:08:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:08:48 INFO - Running setup.py install for mozfile 13:08:48 INFO - Running setup.py install for mozhttpd 13:08:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:08:48 INFO - Running setup.py install for mozinfo 13:08:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:08:48 INFO - Running setup.py install for mozInstall 13:08:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:08:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:08:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:08:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:08:48 INFO - Running setup.py install for mozleak 13:08:48 INFO - Running setup.py install for mozlog 13:08:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:08:49 INFO - Running setup.py install for moznetwork 13:08:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:08:49 INFO - Running setup.py install for mozprocess 13:08:49 INFO - Running setup.py install for mozprofile 13:08:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:08:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:08:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:08:49 INFO - Running setup.py install for mozrunner 13:08:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:08:49 INFO - Running setup.py install for mozscreenshot 13:08:49 INFO - Running setup.py install for moztest 13:08:49 INFO - Running setup.py install for mozversion 13:08:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:08:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:08:50 INFO - Cleaning up... 13:08:50 INFO - Return code: 0 13:08:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:50 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 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08:50 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 13:08:50 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 13:08:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:50 INFO - 'HOME': '/Users/cltbld', 13:08:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:50 INFO - 'LOGNAME': 'cltbld', 13:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:50 INFO - 'MOZ_NO_REMOTE': '1', 13:08:50 INFO - 'NO_EM_RESTART': '1', 13:08:50 INFO - 'PAGER': '/bin/cat', 13:08:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:50 INFO - 'PWD': '/builds/slave/test', 13:08:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:50 INFO - 'SHELL': '/bin/bash', 13:08:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:50 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:50 INFO - 'USER': 'cltbld', 13:08:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:50 INFO - 'XPC_FLAGS': '0x0', 13:08:50 INFO - 'XPC_SERVICE_NAME': '0', 13:08:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:08:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-hXRZUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:08:50 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)) 13:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:08:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-jIdlLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:08:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Vsq4PF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:08:50 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)) 13:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:08:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-GbrkGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:08:50 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)) 13:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:08:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-iCDo9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:08:50 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)) 13:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:08:50 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-rmLKGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:08:51 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)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-tWesXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:08:51 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)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-Z3QkH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:08:51 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)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-7_p3xD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:08:51 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)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-wH51Xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:08:51 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)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-gTY_YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:08:51 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)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-J_d0fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:08:51 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)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-DOjUGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:08:51 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)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-bYRYg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-kRiE7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:08:51 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)) 13:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:08:51 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-DP4CjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:08:52 INFO - Running setup.py (path:/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/pip-hCj4Dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:08:52 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)) 13:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:08:52 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)) 13:08:52 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)) 13:08:52 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)) 13:08:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:08:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:08:54 INFO - Downloading blessings-1.6.tar.gz 13:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:08:54 INFO - Installing collected packages: blessings 13:08:54 INFO - Running setup.py install for blessings 13:08:54 INFO - Successfully installed blessings 13:08:54 INFO - Cleaning up... 13:08:55 INFO - Return code: 0 13:08:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:08:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:08:55 INFO - Reading from file tmpfile_stdout 13:08:55 INFO - Current package versions: 13:08:55 INFO - blessings == 1.6 13:08:55 INFO - blobuploader == 1.2.4 13:08:55 INFO - docopt == 0.6.1 13:08:55 INFO - functools32 == 3.2.3-2 13:08:55 INFO - jsonschema == 2.5.1 13:08:55 INFO - manifestparser == 1.1 13:08:55 INFO - mozInstall == 1.12 13:08:55 INFO - mozcrash == 0.17 13:08:55 INFO - mozdebug == 0.1 13:08:55 INFO - mozdevice == 0.48 13:08:55 INFO - mozfile == 1.2 13:08:55 INFO - mozhttpd == 0.7 13:08:55 INFO - mozinfo == 0.9 13:08:55 INFO - mozleak == 0.1 13:08:55 INFO - mozlog == 3.4 13:08:55 INFO - moznetwork == 0.27 13:08:55 INFO - mozprocess == 0.23 13:08:55 INFO - mozprofile == 0.28 13:08:55 INFO - mozrunner == 6.12 13:08:55 INFO - mozscreenshot == 0.1 13:08:55 INFO - mozsystemmonitor == 0.3 13:08:55 INFO - moztest == 0.7 13:08:55 INFO - mozversion == 1.4 13:08:55 INFO - psutil == 3.1.1 13:08:55 INFO - requests == 1.2.3 13:08:55 INFO - wsgiref == 0.1.2 13:08:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:08:55 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')]} 13:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:55 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')]} 13:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:55 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 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08:55 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 13:08:55 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 13:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:55 INFO - 'HOME': '/Users/cltbld', 13:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:55 INFO - 'LOGNAME': 'cltbld', 13:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:55 INFO - 'MOZ_NO_REMOTE': '1', 13:08:55 INFO - 'NO_EM_RESTART': '1', 13:08:55 INFO - 'PAGER': '/bin/cat', 13:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:55 INFO - 'PWD': '/builds/slave/test', 13:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:55 INFO - 'SHELL': '/bin/bash', 13:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:55 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:55 INFO - 'USER': 'cltbld', 13:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:55 INFO - 'XPC_FLAGS': '0x0', 13:08:55 INFO - 'XPC_SERVICE_NAME': '0', 13:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:55 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)) 13:08:55 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)) 13:08:55 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)) 13:08:55 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)) 13:08:55 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)) 13:08:55 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)) 13:08:55 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)) 13:08:55 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)) 13:08:55 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)) 13:08:55 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)) 13:08:55 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)) 13:08:55 INFO - Cleaning up... 13:08:55 INFO - Return code: 0 13:08:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:08:55 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')]} 13:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:55 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')]} 13:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:08:55 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 0x105df3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe88c8f8e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, '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.xLTOLbPiQu/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.U9911NIOAt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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 13:08:55 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 13:08:55 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 13:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:08:55 INFO - 'HOME': '/Users/cltbld', 13:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:08:55 INFO - 'LOGNAME': 'cltbld', 13:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:08:55 INFO - 'MOZ_NO_REMOTE': '1', 13:08:55 INFO - 'NO_EM_RESTART': '1', 13:08:55 INFO - 'PAGER': '/bin/cat', 13:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:08:55 INFO - 'PWD': '/builds/slave/test', 13:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:08:55 INFO - 'SHELL': '/bin/bash', 13:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:08:55 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:08:55 INFO - 'USER': 'cltbld', 13:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:08:55 INFO - 'XPC_FLAGS': '0x0', 13:08:55 INFO - 'XPC_SERVICE_NAME': '0', 13:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:08:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:08:55 INFO - Cleaning up... 13:08:55 INFO - Return code: 0 13:08:55 INFO - Running post-action listener: _resource_record_post_action 13:08:55 INFO - Running post-action listener: _start_resource_monitoring 13:08:55 INFO - Starting resource monitoring. 13:08:55 INFO - [mozharness: 2016-10-09 20:08:55.923724Z] Finished create-virtualenv step (success) 13:08:55 INFO - [mozharness: 2016-10-09 20:08:55.924745Z] Running install step. 13:08:55 INFO - Running pre-action listener: _resource_record_pre_action 13:08:55 INFO - Running main action method: install 13:08:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:08:56 INFO - Reading from file tmpfile_stdout 13:08:56 INFO - Detecting whether we're running mozinstall >=1.0... 13:08:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:08:56 INFO - Reading from file tmpfile_stdout 13:08:56 INFO - Output received: 13:08:56 INFO - Usage: mozinstall [options] installer 13:08:56 INFO - Options: 13:08:56 INFO - -h, --help show this help message and exit 13:08:56 INFO - -d DEST, --destination=DEST 13:08:56 INFO - Directory to install application into. [default: 13:08:56 INFO - "/builds/slave/test"] 13:08:56 INFO - --app=APP Application being installed. [default: firefox] 13:08:56 INFO - mkdir: /builds/slave/test/build/application 13:08:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:09:30 INFO - Reading from file tmpfile_stdout 13:09:30 INFO - Output received: 13:09:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:09:30 INFO - Running post-action listener: _resource_record_post_action 13:09:30 INFO - [mozharness: 2016-10-09 20:09:30.732894Z] Finished install step (success) 13:09:30 INFO - [mozharness: 2016-10-09 20:09:30.733035Z] Running run-tests step. 13:09:30 INFO - Running pre-action listener: _resource_record_pre_action 13:09:30 INFO - Running main action method: run_tests 13:09:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:09:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:09:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:09:30 INFO - 2016-10-09 13:09:30.747 screenresolution[1648:9877] starting screenresolution argv=screenresolution get 13:09:30 INFO - 2016-10-09 13:09:30.750 screenresolution[1648:9877] Display 0: 1600x1200x32@60 13:09:30 INFO - 2016-10-09 13:09:30.756 screenresolution[1649:9879] starting screenresolution argv=screenresolution list 13:09:30 INFO - Available Modes on Display 0 13:09:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:09:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:09:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:09:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:09:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:09:30 INFO - Intel Iris: 13:09:30 INFO - Chipset Model: Intel Iris 13:09:30 INFO - Type: GPU 13:09:30 INFO - Bus: Built-In 13:09:30 INFO - VRAM (Dynamic, Max): 1536 MB 13:09:30 INFO - Vendor: Intel (0x8086) 13:09:30 INFO - Device ID: 0x0a2e 13:09:30 INFO - Revision ID: 0x0009 13:09:30 INFO - Displays: 13:09:30 INFO - Display: 13:09:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:09:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:09:30 INFO - Display Serial Number: 1600x1200 60 13:09:30 INFO - Main Display: Yes 13:09:30 INFO - Mirror: Off 13:09:30 INFO - Online: Yes 13:09:30 INFO - Rotation: Supported 13:09:30 INFO - Return code: 0 13:09:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:09:30 INFO - Python 2.7.3 13:09:30 INFO - Return code: 0 13:09:30 INFO - grabbing minidump binary from tooltool 13:09:30 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')]} 13:09:30 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 0x105c07d30>, '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 0x105a2ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bffb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:09:30 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 13:09:30 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 13:09:30 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 13:09:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:09:31 INFO - Return code: 0 13:09:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:09:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:09:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:09:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:09:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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 13:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0159 --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 13:09:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xLTOLbPiQu/Render', 13:09:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:09:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:09:31 INFO - 'HOME': '/Users/cltbld', 13:09:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:09:31 INFO - 'LOGNAME': 'cltbld', 13:09:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:09:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:09:31 INFO - 'MOZ_NO_REMOTE': '1', 13:09:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:09:31 INFO - 'NO_EM_RESTART': '1', 13:09:31 INFO - 'PAGER': '/bin/cat', 13:09:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:09:31 INFO - 'PWD': '/builds/slave/test', 13:09:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:09:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:09:31 INFO - 'SHELL': '/bin/bash', 13:09:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.U9911NIOAt/Listeners', 13:09:31 INFO - 'TMPDIR': '/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/', 13:09:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:09:31 INFO - 'USER': 'cltbld', 13:09:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:09:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:09:31 INFO - 'XPC_FLAGS': '0x0', 13:09:31 INFO - 'XPC_SERVICE_NAME': '0', 13:09:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:09:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0159', '--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 13:09:31 INFO - mozversion application_buildid: 20161009120417 13:09:31 INFO - mozversion application_changeset: 35697c37c23bdd2c2c30a06a802cf16c3c9ce037 13:09:31 INFO - mozversion application_display_name: Nightly 13:09:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:09:31 INFO - mozversion application_name: Firefox 13:09:31 INFO - mozversion application_remotingname: firefox 13:09:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:09:31 INFO - mozversion application_vendor: Mozilla 13:09:31 INFO - mozversion application_version: 52.0a1 13:09:31 INFO - mozversion platform_buildid: 20161009120417 13:09:31 INFO - mozversion platform_changeset: 35697c37c23bdd2c2c30a06a802cf16c3c9ce037 13:09:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:09:31 INFO - mozversion platform_version: 52.0a1 13:09:31 INFO - using testdate: 1476043771 13:09:31 INFO - actual date: 1476043771 13:09:31 INFO - starting webserver on 'localhost:49272' 13:09:31 INFO - SUITE-START | Running 2 tests 13:09:31 INFO - TEST-START | dromaeo_css 13:09:31 INFO - operating with platform_type : mac_ 13:09:31 INFO - Initialising browser for dromaeo_css test... 13:09:31 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpiqjLZj/profile http://localhost:49272/getInfo.html) 13:09:33 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 13:09:33 INFO - PROCESS | 1659 | colorDepth:24 13:09:33 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 13:09:33 INFO - PROCESS | 1659 | __metrics 13:09:33 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:09:33 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:09:34 INFO - TEST-INFO | 1659: exit 0 13:09:34 INFO - Browser initialized. 13:09:34 INFO - Running cycle 1/1 for dromaeo_css test... 13:09:34 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpiqjLZj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:09:46 INFO - PROCESS | 1662 | RSS: Main: 234795008 13:09:46 INFO - PROCESS | 1662 | 13:10:38 INFO - PROCESS | 1662 | 1476043838735 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:12:50 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 13:12:52 INFO - PROCESS | 1662 | RSS: Main: 363761664 13:12:52 INFO - PROCESS | 1662 | 13:15:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 13:15:55 INFO - PROCESS | 1662 | RSS: Main: 369266688 13:15:55 INFO - PROCESS | 1662 | 13:18:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 13:18:58 INFO - PROCESS | 1662 | RSS: Main: 338472960 13:18:58 INFO - PROCESS | 1662 | 13:22:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 13:22:01 INFO - PROCESS | 1662 | RSS: Main: 354975744 13:22:01 INFO - PROCESS | 1662 | 13:25:04 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 13:25:05 INFO - PROCESS | 1662 | RSS: Main: 332705792 13:25:05 INFO - PROCESS | 1662 | 13:28:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 13:28:09 INFO - PROCESS | 1662 | RSS: Main: 418942976 13:28:09 INFO - PROCESS | 1662 | 13:28:09 INFO - PROCESS | 1662 | __start_tp_report 13:28:09 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 13:28:09 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 13:28:09 INFO - PROCESS | 1662 | |i|pagename|runs| 13:28:09 INFO - PROCESS | 1662 | |0;dojo.html;3164.9841750791247;3282.967170328296;3307.23933495296;3353.0779035765177;3367.0908854609256;5470.452954704538;5529.142982837657;5601.971990140044;5621.325440947082;5637.943620563793;5501.119820828668;5503.3395992480855;5577.079781835992;5617.466340697637;5650.830475085743;5048.1670524363435;5075.9746201268945;5078.822241221558;5105.566026887719;5136.768845401947;5150.304708864312;5227.79088836446;5231.058409486291;5242.3184985951775;5283.445238249986;5136.614753893473;5186.455422180655;5218.6868787872845;5225.216217567377;5267.683938963674;5009.423916249605;5046;5072.112380333427;5073.492650734934;5095.617828662865;5031.9936012797225;5048.091343558158;5050.949490505084;5101.311322971388;5142.51146141116;5495;5548.056830338852;5577.6733219775015;5603.2435621191025;5630.183623374588;5917.704114794286;5992.981193197167;5994.460498555151;6092.208012958287;6096.878062438726;6113.694315284218;6121.452973575948;6132.172156758825;6142.8021535800535;6148.323684394758;5642.294713160855;5653.660780353192;5653.689047102449;5658.575606829504;5682.801101961453;6102.327983986767;6126.326104128542;6145.231846019247;6233.781817636361;6240.251169859646;5920.348761636216;5974.2233509643465;6001.979663457223;6005.159277701126;6078.027515597571;6118.663473508911;6122.969385153046;6125.4487096160665;6133.938660613426;6144.600600960924;5846.619969701956;6020.518358531307;6057.7879774207695;6062.333143354227;6100.542459315569;5960.552958528127;5978.730957106941;6069.241344831896;6070.939290607126;6081.543884208702;4826.927596086061;4857.587105096107;4874.000829829876;4875.219964805685;4920.015996800583;4344.087741574242;4345.326474396474;4349.978250108792;4399.758013309235;4400.163968845909;5532.5020748132865;5571.331440227118;5581.162825576115;5600.915986260209;5637.605367624227;6089.939100609025;6110.3765049759795;6117.248930028793;6124.991279909952;6168.753249869955;5676.574256930664;5684.63049901755;5687.345955215121;5688.601797874109;5738.8278351649515;5219.5041471060185;5315.202719592092;5330.307060082165;5334.333208348957;5374.27447294618;6036.547258955508;6085.178500902499;6085.786997455068;6162.907556386658;6229.50163986889;5820.73806678692;5823.505002074871;5823.767049317979;5848.444397782204;5904.586678932434;6027.969860150672;6045.123457098608;6063.605865618721;6090.634561926298;6096.542759292982;5647.661140331593;5854.502367298827;5909.70451477433;5958.553108516965;5991.430814072656;5007.524285192901;6100.847478813065;6126.914660831567;6129.064432475528;6146.323904370426;5884.411558844081;5913.46778789911;5913.852153696192;5916.290045194604;5925.111233314865;5726.0838265877255;5768.826935191951;5843.970780146072;5897.734601943009;5925.229720136354;5705.115707065412;5709.800156994485;5712.314522257356;5738.68437235956;5753.654780713171;5764.567657425626;5792.855178620568;5806.82579522615;5828.6502809831545;5829.09649004405;5667.064934285797;5703.714814259353;5704.0588302929555;5704.743286552032;5712.34308054579 13:28:09 INFO - PROCESS | 1662 | |1;ext.html;486.4163004394727;503.54932335559675;513.5403813586839;523.7302789063632;529.8569386265706;30225.697743022592;30804.845975770117;32553.186170345754;40728.98177545563;40864.59135408645;39757.60242397575;42043.57956420435;42078;42084.158316833644;42136;9657.903420965788;9857.753556161117;9865.112139907407;9867.457289849091;9873.358231714952;8799.736007919773;9031.41295815773;9739.853902191437;12111.818322725167;12121.454534545923;11976.461059252355;12032.278063316186;12053.15627906047;12067.034637229044;12101.757964840697;12104.697382565419;12164.574239901649;12181.147319687625;12192.39038048094;12195.999999999956;8641.567921603895;8879.33404994628;11678.532858685643;11993.340366279852;12038.277703337828;40361.99095022619;40723.98190045243;40730;40784.388234176215;40790.59209407927;3461.826908654557;3468.8959331220103;3832.3868181090893;3933.4689816874643;3990.4014397840265;29842.70157298421;31087;31314.843425782725;31364.2953556966;31447.37105257905;3260.7717459777823;3263.5104734289807;3277.983610081958;3348.11275012122;3395.3888300105973;432905.8354708238;462966.37033629906;492659;497867.51066244434;499710;57220.42779572151;57488;57493.71253143791;57496.712516437154;57545;60089;60321.396786032456;60442.39557604455;60475.09287360693;60481.39518604846;15131.773023404658;15579.221038948008;15595.376184952702;15638.609034774221;15646.139462329682;10966.506507207196;11247.156463265124;12093.697657558456;12154.453049612612;12171.269723816422;13189.274589897455;13757.656058598415;15207.31567079484;15279.923600381928;15613.531594052196;5870;6081.483073938764;6383.6488993104895;6424.458130048848;6473.546851720334;881.5548148185254;969.762408209993;978.6281213139104;1034.8758149022165;1042.4162469017374;29720.55419168714;29881;30012;30209.395812083636;30274.24314392158;1009.535613617744;1057.5082586597268;1086.9728256793485;1116.542217690743;1129;14942.70114597702;15247.313870875838;15382.846171538366;15728.68542629141;15741.685166296838;644624;649107;649779;650378;650671;56660;56825;56853.431465685644;57019.30671785806;57049;1224.4489979509256;1260.987390126087;1329.4017692038622;1346.279740338914;1369.3290287758728;29792.851035744683;30681.693183068775;30893;31519.684803151675;31845.36309273848;47850.5214947846;48393;48398.75800621115;48400.51599484101;48478;531705.0976941396;535921.3203933955;536085;537793;811944;20680.586388271848;24739.62890556679;24826.151946448328;24832.255032349058;24857;43352.34971475368;43638;44110.11779764322;44222.77888610536;44940.325895110946;30224.84887575548;36073;36251.09372265715;36920.63079369172;37251.62748372481;32439.513407298437;35413.46879796754;35971.82014089913;36691.89924302275;37285.62714372821;36584.634153658124;36624.45063324105;37396.62603373932;37516.62483375131;39176.020599485244;32206.35587288288;32960.67039329672;33830.49254261139;33856.49215261822;33864.66135338615;24842.50314993726;24996.250112496655;25077.87461062683;25606.487870242865;25686.486270274116 13:28:09 INFO - PROCESS | 1662 | |2;jquery.html;350.09849637184254;367.34245700196647;370.6775105658078;378.24162554079066;400.8817398867334;14207.289635518222;14570.052946558479;15428.382864685413;18178.45464636063;18218;17162.828371716278;17604;18145.7278140828;18341.449756507325;18384.356547520838;5555.138953462209;5914.645121292715;5914.940850591482;5921.615095018831;5925.940740592603;5732.570057245703;5773.422657734235;5846.064629659234;5849.298084229898;5893.292804863401;5839.79560715375;5848.590598658096;5851.122331650266;5852.824415267528;5856.180134781134;5610.607257491977;5842.824715258528;5890.087036509348;5960.672163031031;6017.428344307284;5520.420355862657;5771.971140144315;5780.306363236438;5789.015867302569;5790.942090579082;10764.569417223302;13238.73522529544;13240.668983275398;13243.271620060892;13256.204627722367;2240.0703707961175;2310.2145270607907;2360.929172124839;2367.3608125805936;2908.476474234637;9685.564149613285;9938.15525680318;9993;10212.131968782662;10311.278210525266;1537.5387383784932;1774.290283886443;1850.4263678259783;1858.656148612511;1922.480930148866;110403.99142124347;119322;119636;119726.99999999913;119769.40115299454;11181.32912025281;11347.886521134847;11369.2609980351;11494.195406321645;11539.653810385702;14161.645958850904;14203;14345.6413589659;14346.426142954373;14365.78451323231;8193.804416277657;8229.341652667772;8289.8342033159;8382.664693412195;8393.03480099784;6896;7121.89317160243;7254.96372518134;7456.515326503868;8124.63439145228;3836.827342769582;4449.555044495524;4465.754383508938;4489.326601009875;4575.496695363508;1639.737641977278;1839.6964500857475;1884.3781552087778;1896.8144909431605;1912.923483060662;784.9411294152961;841.5287439034038;883.3904605822091;956.9665061722807;958.5111593087436;19440.513987150436;20744.444166687557;20761.961901905146;20962.371128866158;21175.470613234793;762.6491813765618;926.3654396738256;937.779621788879;956.4930586789013;997.8403455447238;10320;10511.684649460529;10643.627473038561;10877.347359158475;10973.50619222137;601666;601815.7548730953;601843;613286;619930;79142;87839.56080219685;90397;97796.51101744446;112608;955.6416343871049;975.9804803903883;1007.1640538353296;1007.2848277722899;1010.5503051142318;17879.553011174823;18726.53183670419;18785.624287514445;18787.436376908714;18828.52928676794;52736.208956866445;53265.73367133139;54271.72864135654;54735.726321368136;56302.718486407306;60105.699471504115;79126;79776;81639.18360816316;83598;6287.811365659038;6692.6988285526295;6703.765368212089;7827.686892524235;8101.286069166895;11657.88342116602;11791.646250612495;12444.253344799341;12785.36073196355;12871.970242380774;10716.839247411452;11178.776424471627;11343.035841953197;11364.431778411212;11382.715432113931;9114.954425228098;10626.309289896131;10853.457327133454;11288.887111128784;11361.261518001304;10924.235303528676;11021.338719676525;11296.943515282372;11547;11590.014848737952;7915.802104947423;7991.480553763987;8035.718749843727;8120.269175774209;8170.305524030524;8257.05043919957;9899.109080182818;10037.899621003697;10901.56393744273;11417.200795944203 13:28:09 INFO - PROCESS | 1662 | |3;mootools.html;1517.2034681792059;1535.8540938610831;1753.1584839277148;1877.474307193986;1910.551020510179;7375.926240737591;8968.910310896888;8975.640974361026;8976.371653984224;8985.415947963378;7868.09516905556;8087.150849160842;8105.432619716622;8179.222973817493;8798.956005219981;2345.0268138722454;2377.0967032527615;2379.6073647848084;2380.071772008918;2385.7375688674233;2099.454141923096;2344.202970989863;2367.8460900041528;2368.3605426534828;2396.844205126661;2365.5150694107665;2381;2389.7371289158177;2390.2112302940077;2392.820538459614;2344.3201471573225;2359.7876191142796;2367.171489978511;2369.9052037918464;2370.5614461324626;2338.275134708246;2359.315798418457;2360.3980984848927;2361.9173328933575;2365.0894405653776;8910.86633700495;8982.709409100702;8982.865257021085;9004.234640055603;9027.638894444215;5210.348706411699;5406.567474602061;5406.91889621656;5410.296661433988;5416.21464887593;3633.727470439701;3647.434647629622;3690.3541880170865;3717.739758216926;3720;1083.8590983172137;1090.4002798460815;1091.0725882999466;1104.779044191157;1107.7064577886867;11658.708532286675;11737.354445505494;11758.05935525156;11758.706032349173;11778.41107944608;4470.776461176928;4563.178627846953;4738.763061846963;4759.50025247351;4767.356406885096;9931.453770042572;9940.254480913816;9941.155001824782;9944.900550994542;9953.601855925683;5351.384590772111;5353.518183363516;5354.303940487712;5357.785688572413;5357.946420535823;4394.912101757947;4486.304622783474;4568;4881.170201065827;4888.804447822047;3805.8667946621736;3815.9809200954373;3893.766374017568;3979.522457305142;3984.920301593952;1078.385320367383;1244.253447931234;1249.5626530714178;1252.536561472243;1259.6410023143544;844.5397258494136;844.7043534762784;895.2077411490748;899.9865002024975;948.7485816258697;5238.2404551340605;5672.4894759471845;5696.430356964271;5903.645781253139;5914.556408269397;923.8937522185034;938.9108034736689;940.3699521320732;964.9469279189572;981.5681100315838;3702.463477656796;3760.2855457462992;3792.1467669774634;3813.932099012281;3834.175652234783;10617.469126543796;10855.891441085647;10944.835827462595;10960.397178155277;10962.177836662282;10902.073323767569;10904.890951090387;10906.34561926287;10912.181586381053;10926.399048052433;8210.096889342167;8345.123762005023;8357.164283571594;8364.289035431935;8371.916280837235;4892.461829198714;5052.1663925451885;5694.515966142759;5869.090291004901;5964.642121472725;9689.079537443931;9761.414315141114;10033.397996120257;10082;10135.797284054424;9913.256505762241;10179.643712470028;10239.539220734936;10339.327943683937;10345.793084138426;7298.233685463162;7531.983182270324;7613.885791713239;7635.618219088912;7639.809004774925;8224.506529608243;8320.375971802017;8324.62539185726;8327.084020757833;8336.583170841312;7203.315685009916;7257.854842903218;7393.519421237603;7495.437842161969;7580.848383032198;7246.058012458558;7318.597477138595;7414.406847452083;7438.73964411243;7523.172451030492;6828.624425656637;6942.166939967236;6942.479314051366;6955.513114081966;6955.8956615649795;4789.281607758864;4934.432540257916;4959.528844759742;5004.099262132851;5016.523430274118;4846.878828029327;4862.173430516891;4977.875553111056;5103.515166059218;5104.770285337095 13:28:09 INFO - PROCESS | 1662 | |4;prototype.html;293.70482664921764;300.07127939028703;308.9150483617006;313.9905802825916;316.87800196924206;4336.609705126538;4668.579827815496;4914.729689867056;5295.391030031543;5409.513143817056;4664.97667511662;5126.307948426961;5214.035403450364;5236.188390799422;5300.946990530103;3830.693544516432;4117.4441450404265;4119.7940102994735;4149.294619914606;4152.83388664453;3912.3349030664713;4141.420201171838;4143.156295448526;4157;4163.83344666213;3820;3885.834249725085;4002.7198096133284;4111.691623128278;4112.951197444648;4062.2078694654556;4075.572064933199;4075.735077219971;4077.816498257556;4085.2442298174933;3550.715942724576;3553.058439513531;3564.3049605326974;3567.6967457766123;3576.2311103112957;4791.377120974286;4859.465458799529;5168.147255702805;5216.843494695166;5256.369235691704;4148.875533733993;4583.72497650142;4586.922073312769;4589.2198326284615;4629.264025990254;4691.812327506896;4705.952940470585;5168.638195326329;5596.664200148004;5646.632968857052;3717.3866312058476;3745.325841348556;3787.734858559902;3810.713571856864;3837.4819399381286;5259.658122222043;5308.9469105308835;5551.141162301377;5763.769449222068;5847.649141051551;3464.497647841049;3737.0844143185095;3748.6251374862295;3763.2285381496727;3858.479105320746;3432.3821712092063;3447.8276086196092;3929.685625149981;3933.6066393360434;3943.211357728466;4057.3711074782864;4560.019595786922;4591.334256532784;4593.471750748707;4599.448066232007;5384.388571542799;5637.605367624227;5694.174344719992;5696.231008813757;5698.1737648041635;1170.928600330705;1327.2700014991908;1328.7708869296057;1347.690031292808;1351.2365513485047;749.5540153608595;775.8556129708635;784.3019712455919;805.3195050182586;830.9916900831035;4174.499060112746;4418.094290670405;4437.800298986554;4455.391133182068;4460.754658493814;4738.50245724194;4800.271902115236;4847.345608342828;4849.418069831643;4894.755262236874;3962.5046869141356;4172.833086676499;4303.2469317869245;4408.641376863363;4420.292753159479;2031.6692566369052;2221.0005497526176;2225.4992626658873;2445.7798798108256;2587.589763578854;4339.587739164775;4382.890427739332;5007.3240112584335;5009.945922171892;5062.9746851266245;4886.628226523687;4918.360613120343;4942.431620363633;4951.554360107608;4973;4458.576435238647;4686.718796872198;4999.700017998932;5013;5050.696958182521;4177.895552611209;4178.373244013423;4186.618415922677;4723.771819326931;4768.76156192196;4672.275797251432;4755.405574303212;4764.761761911821;4799.66402351832;4814.325994360863;4976.78533052788;5011.045692180126;5119.897602047864;6008.759649614141;6126;3208.1287247913456;4063.593640635913;4072.6945479088595;4075.836966521306;4081.8571350002608;5023.598112151092;5132.640715149903;5807.82576522705;5840.707964601838;5881.705914704162;5349.57203423733;5591.944080559142;5635.859103522281;5643.746031428514;5645.95549823284;4576.81692732287;4972.8756781080765;5072.391313042459;5098.745062746773;5636.408177141341;4410.713303635253;4658.883527911829;4874.878128046827;5246.501582349823;5559.305086864142;4731.597814185833;5192.818251361185;5579.414161513145;5608.775648973995;5621.74702138413;4619.815207391666;5498.780048798003;5515.227868098389;5517.062099443024;5532.529734972572 13:28:09 INFO - PROCESS | 1662 | |5;yui.html;708.7979925721171;730.4120183252483;769.8229407236336;809.2878267124931;813.13807364194;15641.217939103042;15752.291146898388;15753.527394178178;15767.526974190763;16395;14253;14341.28293585319;14349.641258968504;14360.928195359009;14373.928130359334;671.6876652356653;699.3810477727197;803.9799005024892;829.7718127514952;837.5853952293623;845.382870504529;851.3742399336483;855.2901092093579;857.0914830279892;857.391252210931;913.6391125505421;914.3005600715425;915.1351972386097;915.5102020419077;916.7066538707617;910.0717268386242;910.8906931168236;912.9315301352425;912.9680461183826;914.387360468481;523.1864450779037;544.4773017902818;643.8615697624987;666.7199776094052;674.8582797612507;15049.398024079024;15071.924640376837;15108.093514389171;15134;15152.242387880671;13734.313284335743;14112.26342361165;14135;14282.571522854227;14393.208373539452;12558.68603284916;12805.10364274501;12849.229046257162;12920.85886821758;13040.217586944718;3708.1471261609713;3899.844006239747;3922.882313530599;3935.8819235422984;4692;108964.4551777236;109207;109427.90572094257;111088;113492.43253783761;10118.392896426238;10727.249092563447;10979.505922233519;11085;11348.716282093013;20825.583488330452;20871.686924696143;20873.06071226768;20874.851883146574;20945.685814712793;6700.262971073178;7660;7667.118281397599;7948.721794737156;7973.960130199428;4342.739435633872;4376.343548467756;4411.360352748833;4527.909441811145;4563.748993805372;3500.2299494111253;3641.7450778445786;3641.890743277706;3642.0348607619003;3645.0340659725193;573.191799562623;650.4853888308593;671.4695390641436;675.4697562413481;682.3040498691307;528.4741682026331;565.8755073883739;591.5309834202554;633.4488994574749;643.2377632505496;9163;9346.345755797029;9444.591629134527;9539.427634341962;9691.176250018687;515.6184423526563;629.8488362792988;632.6046221111806;657.3065415986181;700.3871612339203;4488.102379524108;4642.976785116053;4670.439547254283;4728.172569800271;4746.967795901291;119945.80054199346;120543.39728301302;127299;136542;173868.1306593459;91804;110509.44745276222;116126;117089.4145529267;127317.36341318233;87272.69090963641;98264.50867745615;100556;103680.96319036864;107410;8646.333872774803;9204.539773011189;9442.858357124785;9791.706059731338;9891.752706182404;57250.85498289928;59101.70449147727;60624.696876515336;63839.53123281326;64925.35074649193;95463;95581;97773;98140;99289.50355248178;12602.764163986754;12864.614061578168;12924.741505170032;12978.33791263465;13508.324583770576;14436.061655992327;14528.20094894791;14534.781978270546;14561.563153105424;14666.120032798066;13462;13605.387757550736;13748.450061997408;13776.517821876187;14185.290735463392;11149.777004460027;13261.270630115436;13645.317734113452;13900.652483687989;14235;9931.205503559842;10580.312279701795;12769.74460510766;13390.732185356044;13623.114497557626;11004.261228812398;11055.28140670886;11272.210945234083;11420.94289528547;11760.647180584596;11178.217524773188;11292.266002709797;11448.82826757616;11484.138689598147;11568.421578920852 13:28:09 INFO - PROCESS | 1662 | __end_tp_report 13:28:09 INFO - PROCESS | 1662 | __start_cc_report 13:28:09 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1603 13:28:09 INFO - PROCESS | 1662 | __end_cc_report 13:28:09 INFO - PROCESS | 1662 | __startTimestamp1476044889114__endTimestamp 13:28:09 INFO - PROCESS | 1662 | 13:28:09 INFO - PROCESS | 1662 | ------- Summary: start ------- 13:28:09 INFO - PROCESS | 1662 | Number of tests: 6 13:28:09 INFO - PROCESS | 1662 | 13:28:09 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5592.34 Median:5712.31 stddev:595.73 (10.4%) stddev-sans-first:566.31 13:28:09 INFO - PROCESS | 1662 | Values: 3165.0 3283.0 3307.2 3353.1 3367.1 5470.5 5529.1 5602.0 5621.3 5637.9 5501.1 5503.3 5577.1 5617.5 5650.8 5048.2 5076.0 5078.8 5105.6 5136.8 5150.3 5227.8 5231.1 5242.3 5283.4 5136.6 5186.5 5218.7 5225.2 5267.7 5009.4 5046.0 5072.1 5073.5 5095.6 5032.0 5048.1 5050.9 5101.3 5142.5 5495.0 5548.1 5577.7 5603.2 5630.2 5917.7 5993.0 5994.5 6092.2 6096.9 6113.7 6121.5 6132.2 6142.8 6148.3 5642.3 5653.7 5653.7 5658.6 5682.8 6102.3 6126.3 6145.2 6233.8 6240.3 5920.3 5974.2 6002.0 6005.2 6078.0 6118.7 6123.0 6125.4 6133.9 6144.6 5846.6 6020.5 6057.8 6062.3 6100.5 5960.6 5978.7 6069.2 6070.9 6081.5 4826.9 4857.6 4874.0 4875.2 4920.0 4344.1 4345.3 4350.0 4399.8 4400.2 5532.5 5571.3 5581.2 5600.9 5637.6 6089.9 6110.4 6117.2 6125.0 6168.8 5676.6 5684.6 5687.3 5688.6 5738.8 5219.5 5315.2 5330.3 5334.3 5374.3 6036.5 6085.2 6085.8 6162.9 6229.5 5820.7 5823.5 5823.8 5848.4 5904.6 6028.0 6045.1 6063.6 6090.6 6096.5 5647.7 5854.5 5909.7 5958.6 5991.4 5007.5 6100.8 6126.9 6129.1 6146.3 5884.4 5913.5 5913.9 5916.3 5925.1 5726.1 5768.8 5844.0 5897.7 5925.2 5705.1 5709.8 5712.3 5738.7 5753.7 5764.6 5792.9 5806.8 5828.7 5829.1 5667.1 5703.7 5704.1 5704.7 5712.3 13:28:09 INFO - PROCESS | 1662 | 13:28:09 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:69822.23 Median:36022.41 stddev:155426.33 (431.5%) stddev-sans-first:155775.16 13:28:09 INFO - PROCESS | 1662 | Values: 486.4 503.5 513.5 523.7 529.9 30225.7 30804.8 32553.2 40729.0 40864.6 39757.6 42043.6 42078.0 42084.2 42136.0 9657.9 9857.8 9865.1 9867.5 9873.4 8799.7 9031.4 9739.9 12111.8 12121.5 11976.5 12032.3 12053.2 12067.0 12101.8 12104.7 12164.6 12181.1 12192.4 12196.0 8641.6 8879.3 11678.5 11993.3 12038.3 40362.0 40724.0 40730.0 40784.4 40790.6 3461.8 3468.9 3832.4 3933.5 3990.4 29842.7 31087.0 31314.8 31364.3 31447.4 3260.8 3263.5 3278.0 3348.1 3395.4 432905.8 462966.4 492659.0 497867.5 499710.0 57220.4 57488.0 57493.7 57496.7 57545.0 60089.0 60321.4 60442.4 60475.1 60481.4 15131.8 15579.2 15595.4 15638.6 15646.1 10966.5 11247.2 12093.7 12154.5 12171.3 13189.3 13757.7 15207.3 15279.9 15613.5 5870.0 6081.5 6383.6 6424.5 6473.5 881.6 969.8 978.6 1034.9 1042.4 29720.6 29881.0 30012.0 30209.4 30274.2 1009.5 1057.5 1087.0 1116.5 1129.0 14942.7 15247.3 15382.8 15728.7 15741.7 644624.0 649107.0 649779.0 650378.0 650671.0 56660.0 56825.0 56853.4 57019.3 57049.0 1224.4 1261.0 1329.4 1346.3 1369.3 29792.9 30681.7 30893.0 31519.7 31845.4 47850.5 48393.0 48398.8 48400.5 48478.0 531705.1 535921.3 536085.0 537793.0 811944.0 20680.6 24739.6 24826.2 24832.3 24857.0 43352.3 43638.0 44110.1 44222.8 44940.3 30224.8 36073.0 36251.1 36920.6 37251.6 32439.5 35413.5 35971.8 36691.9 37285.6 36584.6 36624.5 37396.6 37516.6 39176.0 32206.4 32960.7 33830.5 33856.5 33864.7 24842.5 24996.3 25077.9 25606.5 25686.5 13:28:09 INFO - PROCESS | 1662 | 13:28:09 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33655.56 Median:374.46 stddev:100752.66 (26906.2%) stddev-sans-first:101004.26 13:28:09 INFO - PROCESS | 1662 | Values: 350.1 367.3 370.7 378.2 400.9 14207.3 14570.1 15428.4 18178.5 18218.0 17162.8 17604.0 18145.7 18341.4 18384.4 5555.1 5914.6 5914.9 5921.6 5925.9 5732.6 5773.4 5846.1 5849.3 5893.3 5839.8 5848.6 5851.1 5852.8 5856.2 5610.6 5842.8 5890.1 5960.7 6017.4 5520.4 5772.0 5780.3 5789.0 5790.9 10764.6 13238.7 13240.7 13243.3 13256.2 2240.1 2310.2 2360.9 2367.4 2908.5 9685.6 9938.2 9993.0 10212.1 10311.3 1537.5 1774.3 1850.4 1858.7 1922.5 110404.0 119322.0 119636.0 119727.0 119769.4 11181.3 11347.9 11369.3 11494.2 11539.7 14161.6 14203.0 14345.6 14346.4 14365.8 8193.8 8229.3 8289.8 8382.7 8393.0 6896.0 7121.9 7255.0 7456.5 8124.6 3836.8 4449.6 4465.8 4489.3 4575.5 1639.7 1839.7 1884.4 1896.8 1912.9 784.9 841.5 883.4 957.0 958.5 19440.5 20744.4 20762.0 20962.4 21175.5 762.6 926.4 937.8 956.5 997.8 10320.0 10511.7 10643.6 10877.3 10973.5 601666.0 601815.8 601843.0 613286.0 619930.0 79142.0 87839.6 90397.0 97796.5 112608.0 955.6 976.0 1007.2 1007.3 1010.6 17879.6 18726.5 18785.6 18787.4 18828.5 52736.2 53265.7 54271.7 54735.7 56302.7 60105.7 79126.0 79776.0 81639.2 83598.0 6287.8 6692.7 6703.8 7827.7 8101.3 11657.9 11791.6 12444.3 12785.4 12872.0 10716.8 11178.8 11343.0 11364.4 11382.7 9115.0 10626.3 10853.5 11288.9 11361.3 10924.2 11021.3 11296.9 11547.0 11590.0 7915.8 7991.5 8035.7 8120.3 8170.3 8257.1 9899.1 10037.9 10901.6 11417.2 13:28:09 INFO - PROCESS | 1662 | 13:28:09 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5707.64 Median:4913.45 stddev:3219.17 (65.5%) stddev-sans-first:3212.79 13:28:09 INFO - PROCESS | 1662 | Values: 1517.2 1535.9 1753.2 1877.5 1910.6 7375.9 8968.9 8975.6 8976.4 8985.4 7868.1 8087.2 8105.4 8179.2 8799.0 2345.0 2377.1 2379.6 2380.1 2385.7 2099.5 2344.2 2367.8 2368.4 2396.8 2365.5 2381.0 2389.7 2390.2 2392.8 2344.3 2359.8 2367.2 2369.9 2370.6 2338.3 2359.3 2360.4 2361.9 2365.1 8910.9 8982.7 8982.9 9004.2 9027.6 5210.3 5406.6 5406.9 5410.3 5416.2 3633.7 3647.4 3690.4 3717.7 3720.0 1083.9 1090.4 1091.1 1104.8 1107.7 11658.7 11737.4 11758.1 11758.7 11778.4 4470.8 4563.2 4738.8 4759.5 4767.4 9931.5 9940.3 9941.2 9944.9 9953.6 5351.4 5353.5 5354.3 5357.8 5357.9 4394.9 4486.3 4568.0 4881.2 4888.8 3805.9 3816.0 3893.8 3979.5 3984.9 1078.4 1244.3 1249.6 1252.5 1259.6 844.5 844.7 895.2 900.0 948.7 5238.2 5672.5 5696.4 5903.6 5914.6 923.9 938.9 940.4 964.9 981.6 3702.5 3760.3 3792.1 3813.9 3834.2 10617.5 10855.9 10944.8 10960.4 10962.2 10902.1 10904.9 10906.3 10912.2 10926.4 8210.1 8345.1 8357.2 8364.3 8371.9 4892.5 5052.2 5694.5 5869.1 5964.6 9689.1 9761.4 10033.4 10082.0 10135.8 9913.3 10179.6 10239.5 10339.3 10345.8 7298.2 7532.0 7613.9 7635.6 7639.8 8224.5 8320.4 8324.6 8327.1 8336.6 7203.3 7257.9 7393.5 7495.4 7580.8 7246.1 7318.6 7414.4 7438.7 7523.2 6828.6 6942.2 6942.5 6955.5 6955.9 4789.3 4934.4 4959.5 5004.1 5016.5 4846.9 4862.2 4977.9 5103.5 5104.8 13:28:09 INFO - PROCESS | 1662 | 13:28:09 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4291.26 Median:4661.93 stddev:1294.53 (27.8%) stddev-sans-first:1262.91 13:28:09 INFO - PROCESS | 1662 | Values: 293.7 300.1 308.9 314.0 316.9 4336.6 4668.6 4914.7 5295.4 5409.5 4665.0 5126.3 5214.0 5236.2 5300.9 3830.7 4117.4 4119.8 4149.3 4152.8 3912.3 4141.4 4143.2 4157.0 4163.8 3820.0 3885.8 4002.7 4111.7 4113.0 4062.2 4075.6 4075.7 4077.8 4085.2 3550.7 3553.1 3564.3 3567.7 3576.2 4791.4 4859.5 5168.1 5216.8 5256.4 4148.9 4583.7 4586.9 4589.2 4629.3 4691.8 4706.0 5168.6 5596.7 5646.6 3717.4 3745.3 3787.7 3810.7 3837.5 5259.7 5308.9 5551.1 5763.8 5847.6 3464.5 3737.1 3748.6 3763.2 3858.5 3432.4 3447.8 3929.7 3933.6 3943.2 4057.4 4560.0 4591.3 4593.5 4599.4 5384.4 5637.6 5694.2 5696.2 5698.2 1170.9 1327.3 1328.8 1347.7 1351.2 749.6 775.9 784.3 805.3 831.0 4174.5 4418.1 4437.8 4455.4 4460.8 4738.5 4800.3 4847.3 4849.4 4894.8 3962.5 4172.8 4303.2 4408.6 4420.3 2031.7 2221.0 2225.5 2445.8 2587.6 4339.6 4382.9 5007.3 5009.9 5063.0 4886.6 4918.4 4942.4 4951.6 4973.0 4458.6 4686.7 4999.7 5013.0 5050.7 4177.9 4178.4 4186.6 4723.8 4768.8 4672.3 4755.4 4764.8 4799.7 4814.3 4976.8 5011.0 5119.9 6008.8 6126.0 3208.1 4063.6 4072.7 4075.8 4081.9 5023.6 5132.6 5807.8 5840.7 5881.7 5349.6 5591.9 5635.9 5643.7 5646.0 4576.8 4972.9 5072.4 5098.7 5636.4 4410.7 4658.9 4874.9 5246.5 5559.3 4731.6 5192.8 5579.4 5608.8 5621.7 4619.8 5498.8 5515.2 5517.1 5532.5 13:28:09 INFO - PROCESS | 1662 | 13:28:09 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:23878.11 Median:3676.59 stddev:37180.21 (1011.3%) stddev-sans-first:37243.81 13:28:09 INFO - PROCESS | 1662 | Values: 708.8 730.4 769.8 809.3 813.1 15641.2 15752.3 15753.5 15767.5 16395.0 14253.0 14341.3 14349.6 14360.9 14373.9 671.7 699.4 804.0 829.8 837.6 845.4 851.4 855.3 857.1 857.4 913.6 914.3 915.1 915.5 916.7 910.1 910.9 912.9 913.0 914.4 523.2 544.5 643.9 666.7 674.9 15049.4 15071.9 15108.1 15134.0 15152.2 13734.3 14112.3 14135.0 14282.6 14393.2 12558.7 12805.1 12849.2 12920.9 13040.2 3708.1 3899.8 3922.9 3935.9 4692.0 108964.5 109207.0 109427.9 111088.0 113492.4 10118.4 10727.2 10979.5 11085.0 11348.7 20825.6 20871.7 20873.1 20874.9 20945.7 6700.3 7660.0 7667.1 7948.7 7974.0 4342.7 4376.3 4411.4 4527.9 4563.7 3500.2 3641.7 3641.9 3642.0 3645.0 573.2 650.5 671.5 675.5 682.3 528.5 565.9 591.5 633.4 643.2 9163.0 9346.3 9444.6 9539.4 9691.2 515.6 629.8 632.6 657.3 700.4 4488.1 4643.0 4670.4 4728.2 4747.0 119945.8 120543.4 127299.0 136542.0 173868.1 91804.0 110509.4 116126.0 117089.4 127317.4 87272.7 98264.5 100556.0 103681.0 107410.0 8646.3 9204.5 9442.9 9791.7 9891.8 57250.9 59101.7 60624.7 63839.5 64925.4 95463.0 95581.0 97773.0 98140.0 99289.5 12602.8 12864.6 12924.7 12978.3 13508.3 14436.1 14528.2 14534.8 14561.6 14666.1 13462.0 13605.4 13748.5 13776.5 14185.3 11149.8 13261.3 13645.3 13900.7 14235.0 9931.2 10580.3 12769.7 13390.7 13623.1 11004.3 11055.3 11272.2 11420.9 11760.6 11178.2 11292.3 11448.8 11484.1 11568.4 13:28:09 INFO - PROCESS | 1662 | -------- Summary: end -------- 13:28:09 INFO - PROCESS | 1662 | 13:28:09 INFO - TEST-INFO | 1662: exit 0 13:28:09 INFO - TEST-OK | dromaeo_css | took 1118446ms 13:28:09 INFO - TEST-START | kraken 13:28:09 INFO - operating with platform_type : mac_ 13:28:09 INFO - Initialising browser for kraken test... 13:28:09 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpRsNmfQ/profile http://localhost:49272/getInfo.html) 13:28:11 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 13:28:11 INFO - PROCESS | 1667 | colorDepth:24 13:28:11 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 13:28:11 INFO - PROCESS | 1667 | __metrics 13:28:12 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:28:12 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:28:12 INFO - TEST-INFO | 1667: exit 0 13:28:12 INFO - Browser initialized. 13:28:12 INFO - Running cycle 1/1 for kraken test... 13:28:12 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/T/tmpRsNmfQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:28:24 INFO - PROCESS | 1668 | RSS: Main: 234831872 13:28:24 INFO - PROCESS | 1668 | 13:28:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 13:28:28 INFO - PROCESS | 1668 | RSS: Main: 293928960 13:28:28 INFO - PROCESS | 1668 | 13:28:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 13:28:31 INFO - PROCESS | 1668 | RSS: Main: 294215680 13:28:31 INFO - PROCESS | 1668 | 13:28:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 13:28:35 INFO - PROCESS | 1668 | RSS: Main: 384114688 13:28:35 INFO - PROCESS | 1668 | 13:28:37 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 13:28:38 INFO - PROCESS | 1668 | RSS: Main: 287080448 13:28:38 INFO - PROCESS | 1668 | 13:28:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:28:41 INFO - PROCESS | 1668 | RSS: Main: 289820672 13:28:41 INFO - PROCESS | 1668 | 13:28:43 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 13:28:44 INFO - PROCESS | 1668 | RSS: Main: 326631424 13:28:44 INFO - PROCESS | 1668 | 13:28:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 13:28:48 INFO - PROCESS | 1668 | RSS: Main: 327520256 13:28:48 INFO - PROCESS | 1668 | 13:28:50 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 13:28:51 INFO - PROCESS | 1668 | RSS: Main: 326922240 13:28:51 INFO - PROCESS | 1668 | 13:28:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:28:53 INFO - PROCESS | 1668 | RSS: Main: 302559232 13:28:53 INFO - PROCESS | 1668 | 13:28:55 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:28:56 INFO - PROCESS | 1668 | RSS: Main: 303800320 13:28:56 INFO - PROCESS | 1668 | 13:28:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:28:59 INFO - PROCESS | 1668 | RSS: Main: 305938432 13:28:59 INFO - PROCESS | 1668 | 13:29:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:29:02 INFO - PROCESS | 1668 | RSS: Main: 317890560 13:29:02 INFO - PROCESS | 1668 | 13:29:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:29:06 INFO - PROCESS | 1668 | RSS: Main: 317317120 13:29:06 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 13:29:08 INFO - PROCESS | 1668 | RSS: Main: 361779200 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | __start_tp_report 13:29:08 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 13:29:08 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 13:29:08 INFO - PROCESS | 1668 | |i|pagename|runs| 13:29:08 INFO - PROCESS | 1668 | |0;ai-astar;113;114;113;113;114;113;113;113;113;113 13:29:08 INFO - PROCESS | 1668 | |1;audio-beat-detection;156;128;98;165;96;102;139;141;159;115 13:29:08 INFO - PROCESS | 1668 | |2;audio-dft;184;182;183;184;185;185;181;183;181;182 13:29:08 INFO - PROCESS | 1668 | |3;audio-fft;80;59;60;88;61;59;59;58;84;58 13:29:08 INFO - PROCESS | 1668 | |4;audio-oscillator;86;71;94;70;67;70;92;68;69;90 13:29:08 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;87;89;87;89;86;92;127;135;87 13:29:08 INFO - PROCESS | 1668 | |6;imaging-darkroom;99;97;99;98;114;165;99;99;98;99 13:29:08 INFO - PROCESS | 1668 | |7;imaging-desaturate;86;85;84;86;86;86;83;86;83;86 13:29:08 INFO - PROCESS | 1668 | |8;json-parse-financial;55;55;54;53;55;54;54;53;54;54 13:29:08 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;64;74;67;75;71;70;98;98;70;68 13:29:08 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;87;87;89;95;108;105;105;83;84;87 13:29:08 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;149;148;143;150;129;148;159;156;179;146 13:29:08 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;171;171;173;172;170;225;224;173;210;231 13:29:08 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;68;69;68;67;68;68;69;68;66 13:29:08 INFO - PROCESS | 1668 | __end_tp_report 13:29:08 INFO - PROCESS | 1668 | __start_cc_report 13:29:08 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1982 13:29:08 INFO - PROCESS | 1668 | __end_cc_report 13:29:08 INFO - PROCESS | 1668 | __startTimestamp1476044948534__endTimestamp 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | ------- Summary: start ------- 13:29:08 INFO - PROCESS | 1668 | Number of tests: 14 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:29:08 INFO - PROCESS | 1668 | Values: 113.0 114.0 113.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:157.50 stddev:26.11 (16.6%) stddev-sans-first:25.93 13:29:08 INFO - PROCESS | 1668 | Values: 156.0 128.0 98.0 165.0 96.0 102.0 139.0 141.0 159.0 115.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:1.49 (0.8%) stddev-sans-first:1.54 13:29:08 INFO - PROCESS | 1668 | Values: 184.0 182.0 183.0 184.0 185.0 185.0 181.0 183.0 181.0 182.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:12.19 (20.1%) stddev-sans-first:11.92 13:29:08 INFO - PROCESS | 1668 | Values: 80.0 59.0 60.0 88.0 61.0 59.0 59.0 58.0 84.0 58.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:78.50 stddev:11.25 (14.3%) stddev-sans-first:11.52 13:29:08 INFO - PROCESS | 1668 | Values: 86.0 71.0 94.0 70.0 67.0 70.0 92.0 68.0 69.0 90.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:96.80 Median:88.00 stddev:18.20 (20.7%) stddev-sans-first:19.09 13:29:08 INFO - PROCESS | 1668 | Values: 89.0 87.0 89.0 87.0 89.0 86.0 92.0 127.0 135.0 87.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:99.00 stddev:21.07 (21.3%) stddev-sans-first:22.16 13:29:08 INFO - PROCESS | 1668 | Values: 99.0 97.0 99.0 98.0 114.0 165.0 99.0 99.0 98.0 99.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:86.00 stddev:1.29 (1.5%) stddev-sans-first:1.32 13:29:08 INFO - PROCESS | 1668 | Values: 86.0 85.0 84.0 86.0 86.0 86.0 83.0 86.0 83.0 86.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.74 (1.4%) stddev-sans-first:0.71 13:29:08 INFO - PROCESS | 1668 | Values: 55.0 55.0 54.0 53.0 55.0 54.0 54.0 53.0 54.0 54.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:75.50 Median:72.50 stddev:12.28 (16.9%) stddev-sans-first:12.30 13:29:08 INFO - PROCESS | 1668 | Values: 64.0 74.0 67.0 75.0 71.0 70.0 98.0 98.0 70.0 68.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:87.00 stddev:9.56 (11.0%) stddev-sans-first:9.89 13:29:08 INFO - PROCESS | 1668 | Values: 87.0 87.0 89.0 95.0 108.0 105.0 105.0 83.0 84.0 87.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:149.50 stddev:12.77 (8.5%) stddev-sans-first:13.53 13:29:08 INFO - PROCESS | 1668 | Values: 149.0 148.0 143.0 150.0 129.0 148.0 159.0 156.0 179.0 146.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.00 Median:191.50 stddev:26.76 (14.0%) stddev-sans-first:27.29 13:29:08 INFO - PROCESS | 1668 | Values: 171.0 171.0 173.0 172.0 170.0 225.0 224.0 173.0 210.0 231.0 13:29:08 INFO - PROCESS | 1668 | 13:29:08 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 13:29:08 INFO - PROCESS | 1668 | Values: 68.0 68.0 69.0 68.0 67.0 68.0 68.0 69.0 68.0 66.0 13:29:08 INFO - PROCESS | 1668 | -------- Summary: end -------- 13:29:08 INFO - PROCESS | 1668 | 13:29:09 INFO - TEST-INFO | 1668: exit 0 13:29:09 INFO - TEST-OK | kraken | took 59650ms 13:29:09 INFO - SUITE-END | took 1178s 13:29:09 INFO - Completed test suite (00:19:38) 13:29:09 INFO - javascript benchmark 13:29:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3164.9841750791247, 3282.967170328296, 3307.23933495296, 3353.0779035765177, 3367.0908854609256, 5470.452954704538, 5529.142982837657, 5601.971990140044, 5621.325440947082, 5637.943620563793, 5501.119820828668, 5503.3395992480855, 5577.079781835992, 5617.466340697637, 5650.830475085743, 5048.1670524363435, 5075.9746201268945, 5078.822241221558, 5105.566026887719, 5136.768845401947, 5150.304708864312, 5227.79088836446, 5231.058409486291, 5242.3184985951775, 5283.445238249986, 5136.614753893473, 5186.455422180655, 5218.6868787872845, 5225.216217567377, 5267.683938963674, 5009.423916249605, 5046.0, 5072.112380333427, 5073.492650734934, 5095.617828662865, 5031.9936012797225, 5048.091343558158, 5050.949490505084, 5101.311322971388, 5142.51146141116, 5495.0, 5548.056830338852, 5577.6733219775015, 5603.2435621191025, 5630.183623374588, 5917.704114794286, 5992.981193197167, 5994.460498555151, 6092.208012958287, 6096.878062438726, 6113.694315284218, 6121.452973575948, 6132.172156758825, 6142.8021535800535, 6148.323684394758, 5642.294713160855, 5653.660780353192, 5653.689047102449, 5658.575606829504, 5682.801101961453, 6102.327983986767, 6126.326104128542, 6145.231846019247, 6233.781817636361, 6240.251169859646, 5920.348761636216, 5974.2233509643465, 6001.979663457223, 6005.159277701126, 6078.027515597571, 6118.663473508911, 6122.969385153046, 6125.4487096160665, 6133.938660613426, 6144.600600960924, 5846.619969701956, 6020.518358531307, 6057.7879774207695, 6062.333143354227, 6100.542459315569, 5960.552958528127, 5978.730957106941, 6069.241344831896, 6070.939290607126, 6081.543884208702, 4826.927596086061, 4857.587105096107, 4874.000829829876, 4875.219964805685, 4920.015996800583, 4344.087741574242, 4345.326474396474, 4349.978250108792, 4399.758013309235, 4400.163968845909, 5532.5020748132865, 5571.331440227118, 5581.162825576115, 5600.915986260209, 5637.605367624227, 6089.939100609025, 6110.3765049759795, 6117.248930028793, 6124.991279909952, 6168.753249869955, 5676.574256930664, 5684.63049901755, 5687.345955215121, 5688.601797874109, 5738.8278351649515, 5219.5041471060185, 5315.202719592092, 5330.307060082165, 5334.333208348957, 5374.27447294618, 6036.547258955508, 6085.178500902499, 6085.786997455068, 6162.907556386658, 6229.50163986889, 5820.73806678692, 5823.505002074871, 5823.767049317979, 5848.444397782204, 5904.586678932434, 6027.969860150672, 6045.123457098608, 6063.605865618721, 6090.634561926298, 6096.542759292982, 5647.661140331593, 5854.502367298827, 5909.70451477433, 5958.553108516965, 5991.430814072656, 5007.524285192901, 6100.847478813065, 6126.914660831567, 6129.064432475528, 6146.323904370426, 5884.411558844081, 5913.46778789911, 5913.852153696192, 5916.290045194604, 5925.111233314865, 5726.0838265877255, 5768.826935191951, 5843.970780146072, 5897.734601943009, 5925.229720136354, 5705.115707065412, 5709.800156994485, 5712.314522257356, 5738.68437235956, 5753.654780713171, 5764.567657425626, 5792.855178620568, 5806.82579522615, 5828.6502809831545, 5829.09649004405, 5667.064934285797, 5703.714814259353, 5704.0588302929555, 5704.743286552032, 5712.34308054579], "value": 5555.328169364545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.4163004394727, 503.54932335559675, 513.5403813586839, 523.7302789063632, 529.8569386265706, 30225.697743022592, 30804.845975770117, 32553.186170345754, 40728.98177545563, 40864.59135408645, 39757.60242397575, 42043.57956420435, 42078.0, 42084.158316833644, 42136.0, 9657.903420965788, 9857.753556161117, 9865.112139907407, 9867.457289849091, 9873.358231714952, 8799.736007919773, 9031.41295815773, 9739.853902191437, 12111.818322725167, 12121.454534545923, 11976.461059252355, 12032.278063316186, 12053.15627906047, 12067.034637229044, 12101.757964840697, 12104.697382565419, 12164.574239901649, 12181.147319687625, 12192.39038048094, 12195.999999999956, 8641.567921603895, 8879.33404994628, 11678.532858685643, 11993.340366279852, 12038.277703337828, 40361.99095022619, 40723.98190045243, 40730.0, 40784.388234176215, 40790.59209407927, 3461.826908654557, 3468.8959331220103, 3832.3868181090893, 3933.4689816874643, 3990.4014397840265, 29842.70157298421, 31087.0, 31314.843425782725, 31364.2953556966, 31447.37105257905, 3260.7717459777823, 3263.5104734289807, 3277.983610081958, 3348.11275012122, 3395.3888300105973, 432905.8354708238, 462966.37033629906, 492659.0, 497867.51066244434, 499710.0, 57220.42779572151, 57488.0, 57493.71253143791, 57496.712516437154, 57545.0, 60089.0, 60321.396786032456, 60442.39557604455, 60475.09287360693, 60481.39518604846, 15131.773023404658, 15579.221038948008, 15595.376184952702, 15638.609034774221, 15646.139462329682, 10966.506507207196, 11247.156463265124, 12093.697657558456, 12154.453049612612, 12171.269723816422, 13189.274589897455, 13757.656058598415, 15207.31567079484, 15279.923600381928, 15613.531594052196, 5870.0, 6081.483073938764, 6383.6488993104895, 6424.458130048848, 6473.546851720334, 881.5548148185254, 969.762408209993, 978.6281213139104, 1034.8758149022165, 1042.4162469017374, 29720.55419168714, 29881.0, 30012.0, 30209.395812083636, 30274.24314392158, 1009.535613617744, 1057.5082586597268, 1086.9728256793485, 1116.542217690743, 1129.0, 14942.70114597702, 15247.313870875838, 15382.846171538366, 15728.68542629141, 15741.685166296838, 644624.0, 649107.0, 649779.0, 650378.0, 650671.0, 56660.0, 56825.0, 56853.431465685644, 57019.30671785806, 57049.0, 1224.4489979509256, 1260.987390126087, 1329.4017692038622, 1346.279740338914, 1369.3290287758728, 29792.851035744683, 30681.693183068775, 30893.0, 31519.684803151675, 31845.36309273848, 47850.5214947846, 48393.0, 48398.75800621115, 48400.51599484101, 48478.0, 531705.0976941396, 535921.3203933955, 536085.0, 537793.0, 811944.0, 20680.586388271848, 24739.62890556679, 24826.151946448328, 24832.255032349058, 24857.0, 43352.34971475368, 43638.0, 44110.11779764322, 44222.77888610536, 44940.325895110946, 30224.84887575548, 36073.0, 36251.09372265715, 36920.63079369172, 37251.62748372481, 32439.513407298437, 35413.46879796754, 35971.82014089913, 36691.89924302275, 37285.62714372821, 36584.634153658124, 36624.45063324105, 37396.62603373932, 37516.62483375131, 39176.020599485244, 32206.35587288288, 32960.67039329672, 33830.49254261139, 33856.49215261822, 33864.66135338615, 24842.50314993726, 24996.250112496655, 25077.87461062683, 25606.487870242865, 25686.486270274116], "value": 19761.206376442817, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.09849637184254, 367.34245700196647, 370.6775105658078, 378.24162554079066, 400.8817398867334, 14207.289635518222, 14570.052946558479, 15428.382864685413, 18178.45464636063, 18218.0, 17162.828371716278, 17604.0, 18145.7278140828, 18341.449756507325, 18384.356547520838, 5555.138953462209, 5914.645121292715, 5914.940850591482, 5921.615095018831, 5925.940740592603, 5732.570057245703, 5773.422657734235, 5846.064629659234, 5849.298084229898, 5893.292804863401, 5839.79560715375, 5848.590598658096, 5851.122331650266, 5852.824415267528, 5856.180134781134, 5610.607257491977, 5842.824715258528, 5890.087036509348, 5960.672163031031, 6017.428344307284, 5520.420355862657, 5771.971140144315, 5780.306363236438, 5789.015867302569, 5790.942090579082, 10764.569417223302, 13238.73522529544, 13240.668983275398, 13243.271620060892, 13256.204627722367, 2240.0703707961175, 2310.2145270607907, 2360.929172124839, 2367.3608125805936, 2908.476474234637, 9685.564149613285, 9938.15525680318, 9993.0, 10212.131968782662, 10311.278210525266, 1537.5387383784932, 1774.290283886443, 1850.4263678259783, 1858.656148612511, 1922.480930148866, 110403.99142124347, 119322.0, 119636.0, 119726.99999999913, 119769.40115299454, 11181.32912025281, 11347.886521134847, 11369.2609980351, 11494.195406321645, 11539.653810385702, 14161.645958850904, 14203.0, 14345.6413589659, 14346.426142954373, 14365.78451323231, 8193.804416277657, 8229.341652667772, 8289.8342033159, 8382.664693412195, 8393.03480099784, 6896.0, 7121.89317160243, 7254.96372518134, 7456.515326503868, 8124.63439145228, 3836.827342769582, 4449.555044495524, 4465.754383508938, 4489.326601009875, 4575.496695363508, 1639.737641977278, 1839.6964500857475, 1884.3781552087778, 1896.8144909431605, 1912.923483060662, 784.9411294152961, 841.5287439034038, 883.3904605822091, 956.9665061722807, 958.5111593087436, 19440.513987150436, 20744.444166687557, 20761.961901905146, 20962.371128866158, 21175.470613234793, 762.6491813765618, 926.3654396738256, 937.779621788879, 956.4930586789013, 997.8403455447238, 10320.0, 10511.684649460529, 10643.627473038561, 10877.347359158475, 10973.50619222137, 601666.0, 601815.7548730953, 601843.0, 613286.0, 619930.0, 79142.0, 87839.56080219685, 90397.0, 97796.51101744446, 112608.0, 955.6416343871049, 975.9804803903883, 1007.1640538353296, 1007.2848277722899, 1010.5503051142318, 17879.553011174823, 18726.53183670419, 18785.624287514445, 18787.436376908714, 18828.52928676794, 52736.208956866445, 53265.73367133139, 54271.72864135654, 54735.726321368136, 56302.718486407306, 60105.699471504115, 79126.0, 79776.0, 81639.18360816316, 83598.0, 6287.811365659038, 6692.6988285526295, 6703.765368212089, 7827.686892524235, 8101.286069166895, 11657.88342116602, 11791.646250612495, 12444.253344799341, 12785.36073196355, 12871.970242380774, 10716.839247411452, 11178.776424471627, 11343.035841953197, 11364.431778411212, 11382.715432113931, 9114.954425228098, 10626.309289896131, 10853.457327133454, 11288.887111128784, 11361.261518001304, 10924.235303528676, 11021.338719676525, 11296.943515282372, 11547.0, 11590.014848737952, 7915.802104947423, 7991.480553763987, 8035.718749843727, 8120.269175774209, 8170.305524030524, 8257.05043919957, 9899.109080182818, 10037.899621003697, 10901.56393744273, 11417.200795944203], "value": 9057.778550738243, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1517.2034681792059, 1535.8540938610831, 1753.1584839277148, 1877.474307193986, 1910.551020510179, 7375.926240737591, 8968.910310896888, 8975.640974361026, 8976.371653984224, 8985.415947963378, 7868.09516905556, 8087.150849160842, 8105.432619716622, 8179.222973817493, 8798.956005219981, 2345.0268138722454, 2377.0967032527615, 2379.6073647848084, 2380.071772008918, 2385.7375688674233, 2099.454141923096, 2344.202970989863, 2367.8460900041528, 2368.3605426534828, 2396.844205126661, 2365.5150694107665, 2381.0, 2389.7371289158177, 2390.2112302940077, 2392.820538459614, 2344.3201471573225, 2359.7876191142796, 2367.171489978511, 2369.9052037918464, 2370.5614461324626, 2338.275134708246, 2359.315798418457, 2360.3980984848927, 2361.9173328933575, 2365.0894405653776, 8910.86633700495, 8982.709409100702, 8982.865257021085, 9004.234640055603, 9027.638894444215, 5210.348706411699, 5406.567474602061, 5406.91889621656, 5410.296661433988, 5416.21464887593, 3633.727470439701, 3647.434647629622, 3690.3541880170865, 3717.739758216926, 3720.0, 1083.8590983172137, 1090.4002798460815, 1091.0725882999466, 1104.779044191157, 1107.7064577886867, 11658.708532286675, 11737.354445505494, 11758.05935525156, 11758.706032349173, 11778.41107944608, 4470.776461176928, 4563.178627846953, 4738.763061846963, 4759.50025247351, 4767.356406885096, 9931.453770042572, 9940.254480913816, 9941.155001824782, 9944.900550994542, 9953.601855925683, 5351.384590772111, 5353.518183363516, 5354.303940487712, 5357.785688572413, 5357.946420535823, 4394.912101757947, 4486.304622783474, 4568.0, 4881.170201065827, 4888.804447822047, 3805.8667946621736, 3815.9809200954373, 3893.766374017568, 3979.522457305142, 3984.920301593952, 1078.385320367383, 1244.253447931234, 1249.5626530714178, 1252.536561472243, 1259.6410023143544, 844.5397258494136, 844.7043534762784, 895.2077411490748, 899.9865002024975, 948.7485816258697, 5238.2404551340605, 5672.4894759471845, 5696.430356964271, 5903.645781253139, 5914.556408269397, 923.8937522185034, 938.9108034736689, 940.3699521320732, 964.9469279189572, 981.5681100315838, 3702.463477656796, 3760.2855457462992, 3792.1467669774634, 3813.932099012281, 3834.175652234783, 10617.469126543796, 10855.891441085647, 10944.835827462595, 10960.397178155277, 10962.177836662282, 10902.073323767569, 10904.890951090387, 10906.34561926287, 10912.181586381053, 10926.399048052433, 8210.096889342167, 8345.123762005023, 8357.164283571594, 8364.289035431935, 8371.916280837235, 4892.461829198714, 5052.1663925451885, 5694.515966142759, 5869.090291004901, 5964.642121472725, 9689.079537443931, 9761.414315141114, 10033.397996120257, 10082.0, 10135.797284054424, 9913.256505762241, 10179.643712470028, 10239.539220734936, 10339.327943683937, 10345.793084138426, 7298.233685463162, 7531.983182270324, 7613.885791713239, 7635.618219088912, 7639.809004774925, 8224.506529608243, 8320.375971802017, 8324.62539185726, 8327.084020757833, 8336.583170841312, 7203.315685009916, 7257.854842903218, 7393.519421237603, 7495.437842161969, 7580.848383032198, 7246.058012458558, 7318.597477138595, 7414.406847452083, 7438.73964411243, 7523.172451030492, 6828.624425656637, 6942.166939967236, 6942.479314051366, 6955.513114081966, 6955.8956615649795, 4789.281607758864, 4934.432540257916, 4959.528844759742, 5004.099262132851, 5016.523430274118, 4846.878828029327, 4862.173430516891, 4977.875553111056, 5103.515166059218, 5104.770285337095], "value": 4585.76221371002, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.70482664921764, 300.07127939028703, 308.9150483617006, 313.9905802825916, 316.87800196924206, 4336.609705126538, 4668.579827815496, 4914.729689867056, 5295.391030031543, 5409.513143817056, 4664.97667511662, 5126.307948426961, 5214.035403450364, 5236.188390799422, 5300.946990530103, 3830.693544516432, 4117.4441450404265, 4119.7940102994735, 4149.294619914606, 4152.83388664453, 3912.3349030664713, 4141.420201171838, 4143.156295448526, 4157.0, 4163.83344666213, 3820.0, 3885.834249725085, 4002.7198096133284, 4111.691623128278, 4112.951197444648, 4062.2078694654556, 4075.572064933199, 4075.735077219971, 4077.816498257556, 4085.2442298174933, 3550.715942724576, 3553.058439513531, 3564.3049605326974, 3567.6967457766123, 3576.2311103112957, 4791.377120974286, 4859.465458799529, 5168.147255702805, 5216.843494695166, 5256.369235691704, 4148.875533733993, 4583.72497650142, 4586.922073312769, 4589.2198326284615, 4629.264025990254, 4691.812327506896, 4705.952940470585, 5168.638195326329, 5596.664200148004, 5646.632968857052, 3717.3866312058476, 3745.325841348556, 3787.734858559902, 3810.713571856864, 3837.4819399381286, 5259.658122222043, 5308.9469105308835, 5551.141162301377, 5763.769449222068, 5847.649141051551, 3464.497647841049, 3737.0844143185095, 3748.6251374862295, 3763.2285381496727, 3858.479105320746, 3432.3821712092063, 3447.8276086196092, 3929.685625149981, 3933.6066393360434, 3943.211357728466, 4057.3711074782864, 4560.019595786922, 4591.334256532784, 4593.471750748707, 4599.448066232007, 5384.388571542799, 5637.605367624227, 5694.174344719992, 5696.231008813757, 5698.1737648041635, 1170.928600330705, 1327.2700014991908, 1328.7708869296057, 1347.690031292808, 1351.2365513485047, 749.5540153608595, 775.8556129708635, 784.3019712455919, 805.3195050182586, 830.9916900831035, 4174.499060112746, 4418.094290670405, 4437.800298986554, 4455.391133182068, 4460.754658493814, 4738.50245724194, 4800.271902115236, 4847.345608342828, 4849.418069831643, 4894.755262236874, 3962.5046869141356, 4172.833086676499, 4303.2469317869245, 4408.641376863363, 4420.292753159479, 2031.6692566369052, 2221.0005497526176, 2225.4992626658873, 2445.7798798108256, 2587.589763578854, 4339.587739164775, 4382.890427739332, 5007.3240112584335, 5009.945922171892, 5062.9746851266245, 4886.628226523687, 4918.360613120343, 4942.431620363633, 4951.554360107608, 4973.0, 4458.576435238647, 4686.718796872198, 4999.700017998932, 5013.0, 5050.696958182521, 4177.895552611209, 4178.373244013423, 4186.618415922677, 4723.771819326931, 4768.76156192196, 4672.275797251432, 4755.405574303212, 4764.761761911821, 4799.66402351832, 4814.325994360863, 4976.78533052788, 5011.045692180126, 5119.897602047864, 6008.759649614141, 6126.0, 3208.1287247913456, 4063.593640635913, 4072.6945479088595, 4075.836966521306, 4081.8571350002608, 5023.598112151092, 5132.640715149903, 5807.82576522705, 5840.707964601838, 5881.705914704162, 5349.57203423733, 5591.944080559142, 5635.859103522281, 5643.746031428514, 5645.95549823284, 4576.81692732287, 4972.8756781080765, 5072.391313042459, 5098.745062746773, 5636.408177141341, 4410.713303635253, 4658.883527911829, 4874.878128046827, 5246.501582349823, 5559.305086864142, 4731.597814185833, 5192.818251361185, 5579.414161513145, 5608.775648973995, 5621.74702138413, 4619.815207391666, 5498.780048798003, 5515.227868098389, 5517.062099443024, 5532.529734972572], "value": 3878.013549693039, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.7979925721171, 730.4120183252483, 769.8229407236336, 809.2878267124931, 813.13807364194, 15641.217939103042, 15752.291146898388, 15753.527394178178, 15767.526974190763, 16395.0, 14253.0, 14341.28293585319, 14349.641258968504, 14360.928195359009, 14373.928130359334, 671.6876652356653, 699.3810477727197, 803.9799005024892, 829.7718127514952, 837.5853952293623, 845.382870504529, 851.3742399336483, 855.2901092093579, 857.0914830279892, 857.391252210931, 913.6391125505421, 914.3005600715425, 915.1351972386097, 915.5102020419077, 916.7066538707617, 910.0717268386242, 910.8906931168236, 912.9315301352425, 912.9680461183826, 914.387360468481, 523.1864450779037, 544.4773017902818, 643.8615697624987, 666.7199776094052, 674.8582797612507, 15049.398024079024, 15071.924640376837, 15108.093514389171, 15134.0, 15152.242387880671, 13734.313284335743, 14112.26342361165, 14135.0, 14282.571522854227, 14393.208373539452, 12558.68603284916, 12805.10364274501, 12849.229046257162, 12920.85886821758, 13040.217586944718, 3708.1471261609713, 3899.844006239747, 3922.882313530599, 3935.8819235422984, 4692.0, 108964.4551777236, 109207.0, 109427.90572094257, 111088.0, 113492.43253783761, 10118.392896426238, 10727.249092563447, 10979.505922233519, 11085.0, 11348.716282093013, 20825.583488330452, 20871.686924696143, 20873.06071226768, 20874.851883146574, 20945.685814712793, 6700.262971073178, 7660.0, 7667.118281397599, 7948.721794737156, 7973.960130199428, 4342.739435633872, 4376.343548467756, 4411.360352748833, 4527.909441811145, 4563.748993805372, 3500.2299494111253, 3641.7450778445786, 3641.890743277706, 3642.0348607619003, 3645.0340659725193, 573.191799562623, 650.4853888308593, 671.4695390641436, 675.4697562413481, 682.3040498691307, 528.4741682026331, 565.8755073883739, 591.5309834202554, 633.4488994574749, 643.2377632505496, 9163.0, 9346.345755797029, 9444.591629134527, 9539.427634341962, 9691.176250018687, 515.6184423526563, 629.8488362792988, 632.6046221111806, 657.3065415986181, 700.3871612339203, 4488.102379524108, 4642.976785116053, 4670.439547254283, 4728.172569800271, 4746.967795901291, 119945.80054199346, 120543.39728301302, 127299.0, 136542.0, 173868.1306593459, 91804.0, 110509.44745276222, 116126.0, 117089.4145529267, 127317.36341318233, 87272.69090963641, 98264.50867745615, 100556.0, 103680.96319036864, 107410.0, 8646.333872774803, 9204.539773011189, 9442.858357124785, 9791.706059731338, 9891.752706182404, 57250.85498289928, 59101.70449147727, 60624.696876515336, 63839.53123281326, 64925.35074649193, 95463.0, 95581.0, 97773.0, 98140.0, 99289.50355248178, 12602.764163986754, 12864.614061578168, 12924.741505170032, 12978.33791263465, 13508.324583770576, 14436.061655992327, 14528.20094894791, 14534.781978270546, 14561.563153105424, 14666.120032798066, 13462.0, 13605.387757550736, 13748.450061997408, 13776.517821876187, 14185.290735463392, 11149.777004460027, 13261.270630115436, 13645.317734113452, 13900.652483687989, 14235.0, 9931.205503559842, 10580.312279701795, 12769.74460510766, 13390.732185356044, 13623.114497557626, 11004.261228812398, 11055.28140670886, 11272.210945234083, 11420.94289528547, 11760.647180584596, 11178.217524773188, 11292.266002709797, 11448.82826757616, 11484.138689598147, 11568.421578920852], "value": 7732.353423695968, "unit": "score"}], "name": "dromaeo_css", "value": 7177.737236482197, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 128.0, 98.0, 165.0, 96.0, 102.0, 139.0, 141.0, 159.0, 115.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 183.0, 184.0, 185.0, 185.0, 181.0, 183.0, 181.0, 182.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 88.0, 61.0, 59.0, 59.0, 58.0, 84.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 94.0, 70.0, 67.0, 70.0, 92.0, 68.0, 69.0, 90.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 87.0, 89.0, 86.0, 92.0, 127.0, 135.0, 87.0], "value": 96.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 99.0, 98.0, 114.0, 165.0, 99.0, 99.0, 98.0, 99.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 86.0, 86.0, 86.0, 83.0, 86.0, 83.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 53.0, 55.0, 54.0, 54.0, 53.0, 54.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 67.0, 75.0, 71.0, 70.0, 98.0, 98.0, 70.0, 68.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 89.0, 95.0, 108.0, 105.0, 105.0, 83.0, 84.0, 87.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 143.0, 150.0, 129.0, 148.0, 159.0, 156.0, 179.0, 146.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 173.0, 172.0, 170.0, 225.0, 224.0, 173.0, 210.0, 231.0], "value": 192.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 67.0, 68.0, 68.0, 69.0, 68.0, 66.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1492.2000000000003, "alertThreshold": 2.0}]} 13:29:09 INFO - Return code: 0 13:29:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:29:09 INFO - # TBPL SUCCESS # 13:29:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:29:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:29:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:29:09 INFO - 2016-10-09 13:29:09.849 screenresolution[1672:11965] starting screenresolution argv=screenresolution get 13:29:09 INFO - 2016-10-09 13:29:09.853 screenresolution[1672:11965] Display 0: 1600x1200x32@60 13:29:09 INFO - 2016-10-09 13:29:09.859 screenresolution[1673:11968] starting screenresolution argv=screenresolution list 13:29:09 INFO - Available Modes on Display 0 13:29:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:29:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:29:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:29:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:29:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:29:10 INFO - Intel Iris: 13:29:10 INFO - Chipset Model: Intel Iris 13:29:10 INFO - Type: GPU 13:29:10 INFO - Bus: Built-In 13:29:10 INFO - VRAM (Dynamic, Max): 1536 MB 13:29:10 INFO - Vendor: Intel (0x8086) 13:29:10 INFO - Device ID: 0x0a2e 13:29:10 INFO - Revision ID: 0x0009 13:29:10 INFO - Displays: 13:29:10 INFO - Display: 13:29:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:29:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:29:10 INFO - Display Serial Number: 1600x1200 60 13:29:10 INFO - Main Display: Yes 13:29:10 INFO - Mirror: Off 13:29:10 INFO - Online: Yes 13:29:10 INFO - Rotation: Supported 13:29:10 INFO - Return code: 0 13:29:10 INFO - Running post-action listener: _resource_record_post_action 13:29:10 INFO - [mozharness: 2016-10-09 20:29:10.099049Z] Finished run-tests step (success) 13:29:10 INFO - Running post-run listener: _resource_record_post_run 13:29:10 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 32705536; Write bytes: 707704832; Read time: 306; Write time: 1932 13:29:10 INFO - TinderboxPrint: CPU usage
53.6% 13:29:10 INFO - TinderboxPrint: I/O read bytes / time
32,705,536 / 306 13:29:10 INFO - TinderboxPrint: I/O write bytes / time
707,704,832 / 1,932 13:29:10 INFO - TinderboxPrint: CPU idle
2,250.4 (46.4%) 13:29:10 INFO - TinderboxPrint: CPU system
970.5 (20.0%) 13:29:10 INFO - TinderboxPrint: CPU user
1,632.7 (33.6%) 13:29:10 INFO - TinderboxPrint: Swap in / out
436,183,040 / 0 13:29:10 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389650944; Write bytes: 384178176; Read time: 29083; Write time: 673 13:29:10 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31775744; Write bytes: 297046016; Read time: 293; Write time: 1201 13:29:10 INFO - Running post-run listener: _upload_blobber_files 13:29:10 INFO - Blob upload gear active. 13:29:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:29:10 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 13:29:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:29:10 INFO - (blobuploader) - INFO - Open directory for files ... 13:29:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:29:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:29:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:29:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:29:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:29:11 INFO - (blobuploader) - INFO - Done attempting. 13:29:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:29:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:29:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:29:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:29:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:29:12 INFO - (blobuploader) - INFO - Done attempting. 13:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:29:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:29:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:29:14 INFO - (blobuploader) - INFO - Done attempting. 13:29:14 INFO - (blobuploader) - INFO - Iteration through files over. 13:29:14 INFO - Return code: 0 13:29:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:29:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:29:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/daca4c86c1427528267a27733da4e2e9b67afeb6abaffe57611094484e897a7aff73a512070eb238cce31ebb829daf4b740a8c9daf1e588c6a707fb8bc3449cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/808f19abd1140e04eddea72a213c5a650c2df0be78c80786a3c2fd3d66aadbe9390dfd4e12684401ca1b986618e26e9841b29ae0a170322227dcdef97190bc76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dca63a096c27ee5714d4b9922dffc6f632500ee88ccb4915ef450233588e76337f78a901a8691f900d8f01eb5d62c8960f7c4dd6cac66d63bc4eeebde506e7fb"} 13:29:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:29:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:29:14 INFO - Contents: 13:29:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/daca4c86c1427528267a27733da4e2e9b67afeb6abaffe57611094484e897a7aff73a512070eb238cce31ebb829daf4b740a8c9daf1e588c6a707fb8bc3449cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/808f19abd1140e04eddea72a213c5a650c2df0be78c80786a3c2fd3d66aadbe9390dfd4e12684401ca1b986618e26e9841b29ae0a170322227dcdef97190bc76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dca63a096c27ee5714d4b9922dffc6f632500ee88ccb4915ef450233588e76337f78a901a8691f900d8f01eb5d62c8960f7c4dd6cac66d63bc4eeebde506e7fb"} 13:29:14 INFO - Running post-run listener: copy_logs_to_upload_dir 13:29:14 INFO - Copying logs to upload dir... 13:29:14 INFO - mkdir: /builds/slave/test/build/upload/logs 13:29:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.373831 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-09 13:29:14.817233) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-09 13:29:14.821518) ========= 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.xLTOLbPiQu/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.U9911NIOAt/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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/Firefox/sha512/daca4c86c1427528267a27733da4e2e9b67afeb6abaffe57611094484e897a7aff73a512070eb238cce31ebb829daf4b740a8c9daf1e588c6a707fb8bc3449cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/808f19abd1140e04eddea72a213c5a650c2df0be78c80786a3c2fd3d66aadbe9390dfd4e12684401ca1b986618e26e9841b29ae0a170322227dcdef97190bc76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dca63a096c27ee5714d4b9922dffc6f632500ee88ccb4915ef450233588e76337f78a901a8691f900d8f01eb5d62c8960f7c4dd6cac66d63bc4eeebde506e7fb"} build_url:https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011163 build_url: 'https://queue.taskcluster.net/v1/task/Zg3DFHYfTa2oEHo_pOLMuQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/daca4c86c1427528267a27733da4e2e9b67afeb6abaffe57611094484e897a7aff73a512070eb238cce31ebb829daf4b740a8c9daf1e588c6a707fb8bc3449cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/808f19abd1140e04eddea72a213c5a650c2df0be78c80786a3c2fd3d66aadbe9390dfd4e12684401ca1b986618e26e9841b29ae0a170322227dcdef97190bc76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dca63a096c27ee5714d4b9922dffc6f632500ee88ccb4915ef450233588e76337f78a901a8691f900d8f01eb5d62c8960f7c4dd6cac66d63bc4eeebde506e7fb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-09 13:29:14.852890) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:29:14.856187) ========= 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.xLTOLbPiQu/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.U9911NIOAt/Listeners TMPDIR=/var/folders/9_/5z1bxm9x17x0h1yxkxy862y400000w/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.005371 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-09 13:29:14.904715) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-09 13:29:14.905000) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-09 13:29:14.908313) ========= ========= Total master_lag: 1.21 =========