builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0158 starttime: 1476481783.74 results: success (0) buildid: 20161014122035 builduid: afaa80a6460644fea1310223174d0ee2 revision: 8ca6edde188aa0bf9ac707b5e1e7b95792e413ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:43.738581) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:43.738998) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:43.739269) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:44.501729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:44.505042) ========= 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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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-14 14:49:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.81M=0.001s 2016-10-14 14:49:44 (7.81 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079508 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:44.603576) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:44.606900) ========= 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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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.019590 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:44.654794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 14:49:44.658147) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8ca6edde188aa0bf9ac707b5e1e7b95792e413ff --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 8ca6edde188aa0bf9ac707b5e1e7b95792e413ff --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IFHOfYBjy5/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.sRcwcNWX5I/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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-14 14:49:44,729 truncating revision to first 12 chars 2016-10-14 14:49:44,730 Setting DEBUG logging. 2016-10-14 14:49:44,730 attempt 1/10 2016-10-14 14:49:44,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8ca6edde188a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 14:49:45,982 unpacking tar archive at: mozilla-central-8ca6edde188a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.513167 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 14:49:46.193403) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:46.193975) ========= script_repo_revision: 8ca6edde188aa0bf9ac707b5e1e7b95792e413ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:46.194583) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:46.195053) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 14:49:46.213903) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-14 14:49:46.214292) ========= /tools/buildbot/bin/python -u 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', '-u', '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:49:46 INFO - MultiFileLogger online at 20161014 14:49:46 in /builds/slave/test 14:49:46 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 14:49:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:49:46 INFO - {'append_to_log': False, 14:49:46 INFO - 'base_work_dir': '/builds/slave/test', 14:49:46 INFO - 'blob_upload_branch': 'Firefox', 14:49:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:49:46 INFO - 'branch': 'Firefox', 14:49:46 INFO - 'buildbot_json_path': 'buildprops.json', 14:49:46 INFO - 'config_files': ('talos/mac_config.py',), 14:49:46 INFO - 'default_actions': ('clobber', 14:49:46 INFO - 'read-buildbot-config', 14:49:46 INFO - 'download-and-extract', 14:49:46 INFO - 'populate-webroot', 14:49:46 INFO - 'create-virtualenv', 14:49:46 INFO - 'install', 14:49:46 INFO - 'run-tests'), 14:49:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:49:46 INFO - 'download_minidump_stackwalk': True, 14:49:46 INFO - 'download_symbols': 'ondemand', 14:49:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:49:46 INFO - 'tooltool.py': '/tools/tooltool.py', 14:49:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:49:46 INFO - '/tools/misc-python/virtualenv.py')}, 14:49:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:49:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:49:46 INFO - 'installer_path': 'installer.exe', 14:49:46 INFO - 'log_level': 'info', 14:49:46 INFO - 'log_name': 'talos', 14:49:46 INFO - 'log_to_console': True, 14:49:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:49:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:49:46 INFO - 'opt_config_files': (), 14:49:46 INFO - 'pip_index': False, 14:49:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:49:46 INFO - 'cmd': ('bash', 14:49:46 INFO - '-c', 14:49:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:49:46 INFO - 'enabled': True, 14:49:46 INFO - 'halt_on_failure': False, 14:49:46 INFO - 'name': 'check_screen_resolution'},), 14:49:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:49:46 INFO - 'cmd': ('bash', 14:49:46 INFO - '-c', 14:49:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:49:46 INFO - 'enabled': True, 14:49:46 INFO - 'halt_on_failure': False, 14:49:46 INFO - 'name': 'check_screen_resolution'},), 14:49:46 INFO - 'run_cmd_checks_enabled': True, 14:49:46 INFO - 'sps_profile': False, 14:49:46 INFO - 'sps_profile_interval': 0, 14:49:46 INFO - 'suite': 'dromaeojs', 14:49:46 INFO - 'system_bits': '32', 14:49:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:49:46 INFO - 'title': 't-yosemite-r7-0158', 14:49:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:49:46 INFO - 'use_talos_json': True, 14:49:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:49:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:49:46 INFO - 'work_dir': 'build'} 14:49:46 INFO - [mozharness: 2016-10-14 21:49:46.407623Z] Running clobber step. 14:49:46 INFO - Running pre-action listener: _resource_record_pre_action 14:49:46 INFO - Running main action method: clobber 14:49:46 INFO - rmtree: /builds/slave/test/build 14:49:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:49:47 INFO - Running post-action listener: _resource_record_post_action 14:49:47 INFO - [mozharness: 2016-10-14 21:49:47.396307Z] Finished clobber step (success) 14:49:47 INFO - [mozharness: 2016-10-14 21:49:47.396422Z] Running read-buildbot-config step. 14:49:47 INFO - Running pre-action listener: _resource_record_pre_action 14:49:47 INFO - Running main action method: read_buildbot_config 14:49:47 INFO - Using buildbot properties: 14:49:47 INFO - { 14:49:47 INFO - "project": "", 14:49:47 INFO - "product": "firefox", 14:49:47 INFO - "script_repo_revision": "production", 14:49:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:49:47 INFO - "repository": "", 14:49:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:49:47 INFO - "buildid": "20161014122035", 14:49:47 INFO - "pgo_build": "False", 14:49:47 INFO - "basedir": "/builds/slave/test", 14:49:47 INFO - "buildnumber": 4, 14:49:47 INFO - "slavename": "t-yosemite-r7-0158", 14:49:47 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:49:47 INFO - "platform": "yosemite_r7", 14:49:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:49:47 INFO - "branch": "mozilla-central", 14:49:47 INFO - "repo_path": "mozilla-central", 14:49:47 INFO - "revision": "8ca6edde188aa0bf9ac707b5e1e7b95792e413ff", 14:49:47 INFO - "stage_platform": "macosx64", 14:49:47 INFO - "builduid": "afaa80a6460644fea1310223174d0ee2", 14:49:47 INFO - "slavebuilddir": "test" 14:49:47 INFO - } 14:49:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 14:49:47 INFO - Running post-action listener: _resource_record_post_action 14:49:47 INFO - [mozharness: 2016-10-14 21:49:47.400636Z] Finished read-buildbot-config step (success) 14:49:47 INFO - [mozharness: 2016-10-14 21:49:47.400921Z] Running download-and-extract step. 14:49:47 INFO - Running pre-action listener: _resource_record_pre_action 14:49:47 INFO - Running main action method: download_and_extract 14:49:47 INFO - mkdir: /builds/slave/test/build/tests 14:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:47 INFO - trying https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 14:49:47 INFO - Downloading https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/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 14:49:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/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 14:49:48 INFO - Downloaded 1199 bytes. 14:49:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:49:48 INFO - Using the following test package requirements: 14:49:48 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:49:48 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:49:48 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 14:49:48 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:49:48 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 14:49:48 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:49:48 INFO - u'jsshell-mac.zip'], 14:49:48 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:49:48 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 14:49:48 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:49:48 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:49:48 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 14:49:48 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:49:48 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 14:49:48 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:49:48 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 14:49:48 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:49:48 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 14:49:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:49:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:49:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:49:48 INFO - Fetch https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:49:48 INFO - Http code: 200 14:49:48 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 14:49:48 INFO - x-amz-cf-id: rnHG1KGKd44wlsrZVnQJUc5htasqvqFc6z2FPppunywpJhyzvDKsOQ== 14:49:48 INFO - x-amz-version-id: qPegtipEAlw9GLFF31N5KWLyJgPq9JBj 14:49:49 INFO - Expected file size: 16516903 14:49:49 INFO - Obtained file size: 16516903 14:49:55 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 14:49:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:49:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:49:55 INFO - Fetch https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:49:56 INFO - Http code: 200 14:49:56 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 14:49:56 INFO - x-amz-cf-id: 06OhrXo46AFkOjX4eBiwkrNDDw5jRaeRSo-5YiUDQ0skn6fyKE-XDg== 14:49:56 INFO - x-amz-version-id: qPegtipEAlw9GLFF31N5KWLyJgPq9JBj 14:49:56 INFO - Expected file size: 16516903 14:49:56 INFO - Obtained file size: 16516903 14:50:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 14:50:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:50:03 INFO - Fetch https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 14:50:03 INFO - Http code: 200 14:50:03 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 14:50:03 INFO - x-amz-cf-id: 0KNKKQsGJNrwSa5PtPhzzrEGp7pacVe_7OIAyGlQH3GP8g7G05O1Nw== 14:50:03 INFO - x-amz-version-id: 0rhrw5z2cCYrSjOIow2rgfdavnB1RqTw 14:50:03 INFO - Expected file size: 14409006 14:50:03 INFO - Obtained file size: 14409006 14:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:04 INFO - trying https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:50:04 INFO - Downloading https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 14:50:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/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 14:50:07 INFO - Downloaded 111930418 bytes. 14:50:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:50:07 INFO - mkdir: /builds/slave/test/properties 14:50:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:50:07 INFO - Writing to file /builds/slave/test/properties/build_url 14:50:07 INFO - Contents: 14:50:07 INFO - build_url:https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:50:07 INFO - Running post-action listener: _resource_record_post_action 14:50:07 INFO - Running post-action listener: set_extra_try_arguments 14:50:07 INFO - [mozharness: 2016-10-14 21:50:07.844754Z] Finished download-and-extract step (success) 14:50:07 INFO - [mozharness: 2016-10-14 21:50:07.844902Z] Running populate-webroot step. 14:50:07 INFO - Running pre-action listener: _resource_record_pre_action 14:50:07 INFO - Running main action method: populate_webroot 14:50:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:50:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:50:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:50:07 INFO - '--tppagecycles', 14:50:07 INFO - '7'], 14:50:07 INFO - 'tests': ['tsvgm']}}, 14:50:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:50:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:50:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:50:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:50:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:50:07 INFO - 'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:50:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:50:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:50:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:50:07 INFO - 'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['damp', 'tps']}, 14:50:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:50:07 INFO - 'tests': ['damp', 'tps']}, 14:50:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['dromaeo_dom']}, 14:50:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:50:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['basic_compositor_video']}, 14:50:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:50:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['a11yr', 14:50:07 INFO - 'ts_paint', 14:50:07 INFO - 'tpaint', 14:50:07 INFO - 'sessionrestore', 14:50:07 INFO - 'sessionrestore_no_auto_restore', 14:50:07 INFO - 'tabpaint']}, 14:50:07 INFO - 'other-e10s': {'tests': ['a11yr', 14:50:07 INFO - 'ts_paint', 14:50:07 INFO - 'tpaint', 14:50:07 INFO - 'sessionrestore', 14:50:07 INFO - 'sessionrestore_no_auto_restore', 14:50:07 INFO - 'tabpaint']}, 14:50:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:50:07 INFO - 'ts_paint', 14:50:07 INFO - 'tpaint', 14:50:07 INFO - 'sessionrestore', 14:50:07 INFO - 'sessionrestore_no_auto_restore', 14:50:07 INFO - 'tabpaint']}, 14:50:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:50:07 INFO - 'ts_paint', 14:50:07 INFO - 'tpaint', 14:50:07 INFO - 'sessionrestore', 14:50:07 INFO - 'sessionrestore_no_auto_restore', 14:50:07 INFO - 'tabpaint']}, 14:50:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['a11yr', 14:50:07 INFO - 'ts_paint', 14:50:07 INFO - 'tpaint', 14:50:07 INFO - 'sessionrestore', 14:50:07 INFO - 'sessionrestore_no_auto_restore', 14:50:07 INFO - 'tabpaint']}, 14:50:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['a11yr', 14:50:07 INFO - 'ts_paint', 14:50:07 INFO - 'tpaint', 14:50:07 INFO - 'sessionrestore', 14:50:07 INFO - 'sessionrestore_no_auto_restore', 14:50:07 INFO - 'tabpaint']}, 14:50:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['tsvgx', 14:50:07 INFO - 'tsvgr_opacity', 14:50:07 INFO - 'tart', 14:50:07 INFO - 'tscrollx', 14:50:07 INFO - 'cart']}, 14:50:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:50:07 INFO - 'tsvgr_opacity', 14:50:07 INFO - 'tart', 14:50:07 INFO - 'tscrollx', 14:50:07 INFO - 'cart']}, 14:50:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:50:07 INFO - 'talos_options': ['--disable-e10s'], 14:50:07 INFO - 'tests': ['tp5o']}, 14:50:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:50:07 INFO - 'tests': ['tp5o']}, 14:50:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:50:07 INFO - 'talos_options': ['--disable-e10s', 14:50:07 INFO - '--xperf_path', 14:50:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:50:07 INFO - 'tests': ['tp5n']}, 14:50:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:50:07 INFO - 'talos_options': ['--xperf_path', 14:50:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:50:07 INFO - 'tests': ['tp5n']}}, 14:50:07 INFO - 'talos.zip': {'path': '', 14:50:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:50:07 INFO - Running post-action listener: _resource_record_post_action 14:50:07 INFO - [mozharness: 2016-10-14 21:50:07.863965Z] Finished populate-webroot step (success) 14:50:07 INFO - [mozharness: 2016-10-14 21:50:07.864082Z] Running create-virtualenv step. 14:50:07 INFO - Running pre-action listener: _resource_record_pre_action 14:50:07 INFO - Running main action method: create_virtualenv 14:50:07 INFO - Creating virtualenv /builds/slave/test/build/venv 14:50:07 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 14:50:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:50:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:50:07 INFO - Using real prefix '/tools/python27' 14:50:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:50:08 INFO - Installing distribute.............................................................................................................................................................................................done. 14:50:11 INFO - Installing pip.................done. 14:50:11 INFO - Return code: 0 14:50:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:50:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:50:11 INFO - Reading from file tmpfile_stdout 14:50:11 INFO - Output received: 14:50:11 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) 14:50:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:50:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:50:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:11 INFO - 'HOME': '/Users/cltbld', 14:50:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:11 INFO - 'LOGNAME': 'cltbld', 14:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:11 INFO - 'MOZ_NO_REMOTE': '1', 14:50:11 INFO - 'NO_EM_RESTART': '1', 14:50:11 INFO - 'PAGER': '/bin/cat', 14:50:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:11 INFO - 'PWD': '/builds/slave/test', 14:50:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:11 INFO - 'SHELL': '/bin/bash', 14:50:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:11 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:11 INFO - 'USER': 'cltbld', 14:50:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:11 INFO - 'XPC_FLAGS': '0x0', 14:50:11 INFO - 'XPC_SERVICE_NAME': '0', 14:50:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:12 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 14:50:12 INFO - Cleaning up... 14:50:12 INFO - Return code: 0 14:50:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:12 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 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:12 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 14:50:12 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 14:50:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:12 INFO - 'HOME': '/Users/cltbld', 14:50:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:12 INFO - 'LOGNAME': 'cltbld', 14:50:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:12 INFO - 'MOZ_NO_REMOTE': '1', 14:50:12 INFO - 'NO_EM_RESTART': '1', 14:50:12 INFO - 'PAGER': '/bin/cat', 14:50:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:12 INFO - 'PWD': '/builds/slave/test', 14:50:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:12 INFO - 'SHELL': '/bin/bash', 14:50:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:12 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:12 INFO - 'USER': 'cltbld', 14:50:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:12 INFO - 'XPC_FLAGS': '0x0', 14:50:12 INFO - 'XPC_SERVICE_NAME': '0', 14:50:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:12 INFO - Downloading/unpacking psutil>=3.1.1 14:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:50:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:50:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:50:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:50:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:50:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:50:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:50:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:50:15 INFO - Installing collected packages: psutil 14:50:15 INFO - Running setup.py install for psutil 14:50:16 INFO - building 'psutil._psutil_osx' extension 14:50:16 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 14:50:16 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 14:50:16 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 14:50:16 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 14:50:16 INFO - building 'psutil._psutil_posix' extension 14:50:16 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 14:50:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:50:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:50:16 INFO - ^ 14:50:16 INFO - 1 warning generated. 14:50:16 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 14:50:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:50:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:50:16 INFO - Successfully installed psutil 14:50:16 INFO - Cleaning up... 14:50:16 INFO - Return code: 0 14:50:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:50:16 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')]} 14:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:16 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')]} 14:50:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:16 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 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:16 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 14:50:16 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 14:50:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:16 INFO - 'HOME': '/Users/cltbld', 14:50:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:16 INFO - 'LOGNAME': 'cltbld', 14:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:16 INFO - 'MOZ_NO_REMOTE': '1', 14:50:16 INFO - 'NO_EM_RESTART': '1', 14:50:16 INFO - 'PAGER': '/bin/cat', 14:50:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:16 INFO - 'PWD': '/builds/slave/test', 14:50:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:16 INFO - 'SHELL': '/bin/bash', 14:50:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:16 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:16 INFO - 'USER': 'cltbld', 14:50:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:16 INFO - 'XPC_FLAGS': '0x0', 14:50:16 INFO - 'XPC_SERVICE_NAME': '0', 14:50:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:50:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:50:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:50:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:50:19 INFO - Installing collected packages: mozsystemmonitor 14:50:19 INFO - Running setup.py install for mozsystemmonitor 14:50:19 INFO - Successfully installed mozsystemmonitor 14:50:19 INFO - Cleaning up... 14:50:19 INFO - Return code: 0 14:50:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:50:19 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')]} 14:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:19 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')]} 14:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:19 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 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:19 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 14:50:19 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 14:50:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:19 INFO - 'HOME': '/Users/cltbld', 14:50:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:19 INFO - 'LOGNAME': 'cltbld', 14:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:19 INFO - 'MOZ_NO_REMOTE': '1', 14:50:19 INFO - 'NO_EM_RESTART': '1', 14:50:19 INFO - 'PAGER': '/bin/cat', 14:50:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:19 INFO - 'PWD': '/builds/slave/test', 14:50:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:19 INFO - 'SHELL': '/bin/bash', 14:50:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:19 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:19 INFO - 'USER': 'cltbld', 14:50:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:19 INFO - 'XPC_FLAGS': '0x0', 14:50:19 INFO - 'XPC_SERVICE_NAME': '0', 14:50:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:20 INFO - Downloading/unpacking jsonschema==2.5.1 14:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:50:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:50:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:50:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:50:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:50:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:50:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:50:22 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 14:50:22 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 14:50:22 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 14:50:22 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 14:50:22 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 14:50:22 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 14:50:23 INFO - Downloading functools32-3.2.3-2.tar.gz 14:50:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:50:23 INFO - warning: no files found matching '*.txt' 14:50:23 INFO - no previously-included directories found matching 'build' 14:50:23 INFO - no previously-included directories found matching 'dist' 14:50:23 INFO - no previously-included directories found matching '.git*' 14:50:23 INFO - Installing collected packages: jsonschema, functools32 14:50:23 INFO - Running setup.py install for functools32 14:50:23 INFO - warning: no files found matching '*.txt' 14:50:23 INFO - no previously-included directories found matching 'build' 14:50:23 INFO - no previously-included directories found matching 'dist' 14:50:23 INFO - no previously-included directories found matching '.git*' 14:50:23 INFO - Successfully installed jsonschema functools32 14:50:23 INFO - Cleaning up... 14:50:23 INFO - Return code: 0 14:50:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:23 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 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:23 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 14:50:23 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 14:50:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:23 INFO - 'HOME': '/Users/cltbld', 14:50:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:23 INFO - 'LOGNAME': 'cltbld', 14:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:23 INFO - 'MOZ_NO_REMOTE': '1', 14:50:23 INFO - 'NO_EM_RESTART': '1', 14:50:23 INFO - 'PAGER': '/bin/cat', 14:50:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:23 INFO - 'PWD': '/builds/slave/test', 14:50:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:23 INFO - 'SHELL': '/bin/bash', 14:50:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:23 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:23 INFO - 'USER': 'cltbld', 14:50:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:23 INFO - 'XPC_FLAGS': '0x0', 14:50:23 INFO - 'XPC_SERVICE_NAME': '0', 14:50:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:50:23 INFO - Cleaning up... 14:50:23 INFO - Return code: 0 14:50:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:50:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:23 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 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:23 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 14:50:23 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 14:50:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:23 INFO - 'HOME': '/Users/cltbld', 14:50:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:23 INFO - 'LOGNAME': 'cltbld', 14:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:23 INFO - 'MOZ_NO_REMOTE': '1', 14:50:23 INFO - 'NO_EM_RESTART': '1', 14:50:23 INFO - 'PAGER': '/bin/cat', 14:50:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:23 INFO - 'PWD': '/builds/slave/test', 14:50:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:23 INFO - 'SHELL': '/bin/bash', 14:50:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:23 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:23 INFO - 'USER': 'cltbld', 14:50:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:23 INFO - 'XPC_FLAGS': '0x0', 14:50:23 INFO - 'XPC_SERVICE_NAME': '0', 14:50:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:23 INFO - Downloading/unpacking blobuploader==1.2.4 14:50:23 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 14:50:23 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 14:50:23 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 14:50:23 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 14:50:23 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 14:50:23 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 14:50:26 INFO - Downloading blobuploader-1.2.4.tar.gz 14:50:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:50:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:50:26 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 14:50:26 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 14:50:26 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 14:50:26 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 14:50:26 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 14:50:26 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 14:50:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:50:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:50:27 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 14:50:27 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 14:50:27 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 14:50:27 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 14:50:27 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 14:50:27 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 14:50:27 INFO - Downloading docopt-0.6.1.tar.gz 14:50:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:50:27 INFO - Installing collected packages: blobuploader, requests, docopt 14:50:27 INFO - Running setup.py install for blobuploader 14:50:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:50:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:50:27 INFO - Running setup.py install for requests 14:50:28 INFO - Running setup.py install for docopt 14:50:28 INFO - Successfully installed blobuploader requests docopt 14:50:28 INFO - Cleaning up... 14:50:28 INFO - Return code: 0 14:50:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:50: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')]} 14:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50: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')]} 14:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:28 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 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:28 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 14:50:28 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 14:50:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:28 INFO - 'HOME': '/Users/cltbld', 14:50:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:28 INFO - 'LOGNAME': 'cltbld', 14:50:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:28 INFO - 'MOZ_NO_REMOTE': '1', 14:50:28 INFO - 'NO_EM_RESTART': '1', 14:50:28 INFO - 'PAGER': '/bin/cat', 14:50:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:28 INFO - 'PWD': '/builds/slave/test', 14:50:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:28 INFO - 'SHELL': '/bin/bash', 14:50:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:28 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:28 INFO - 'USER': 'cltbld', 14:50:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:28 INFO - 'XPC_FLAGS': '0x0', 14:50:28 INFO - 'XPC_SERVICE_NAME': '0', 14:50:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:50:28 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-w7quoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:50:28 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-ZvCPYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-B_CzGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-fs29iA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-FaxlNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-gaOiVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-bZxVsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-kvPNCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-eZJ6oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-Hjhn7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-sBdEO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:50:29 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-V9yKi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:50:30 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-hHIMW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:50:30 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-v0Rsd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:50:30 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-s6M4zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:50:30 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-aSVkNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:50:30 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-ARzqYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:50:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:50:30 INFO - Running setup.py install for manifestparser 14:50:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:50:30 INFO - Running setup.py install for mozcrash 14:50:30 INFO - Running setup.py install for mozdebug 14:50:31 INFO - Running setup.py install for mozdevice 14:50:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:50:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:50:31 INFO - Running setup.py install for mozfile 14:50:31 INFO - Running setup.py install for mozhttpd 14:50:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:50:31 INFO - Running setup.py install for mozinfo 14:50:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:50:31 INFO - Running setup.py install for mozInstall 14:50:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:50:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:50:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:50:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:50:31 INFO - Running setup.py install for mozleak 14:50:31 INFO - Running setup.py install for mozlog 14:50:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:50:32 INFO - Running setup.py install for moznetwork 14:50:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:50:32 INFO - Running setup.py install for mozprocess 14:50:32 INFO - Running setup.py install for mozprofile 14:50:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:50:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:50:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:50:32 INFO - Running setup.py install for mozrunner 14:50:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:50:32 INFO - Running setup.py install for mozscreenshot 14:50:32 INFO - Running setup.py install for moztest 14:50:33 INFO - Running setup.py install for mozversion 14:50:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:50:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:50:33 INFO - Cleaning up... 14:50:33 INFO - Return code: 0 14:50:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:50: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')]} 14:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50: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')]} 14:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:33 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 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:33 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 14:50:33 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 14:50:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:33 INFO - 'HOME': '/Users/cltbld', 14:50:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:33 INFO - 'LOGNAME': 'cltbld', 14:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:33 INFO - 'MOZ_NO_REMOTE': '1', 14:50:33 INFO - 'NO_EM_RESTART': '1', 14:50:33 INFO - 'PAGER': '/bin/cat', 14:50:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:33 INFO - 'PWD': '/builds/slave/test', 14:50:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:33 INFO - 'SHELL': '/bin/bash', 14:50:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:33 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:33 INFO - 'USER': 'cltbld', 14:50:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:33 INFO - 'XPC_FLAGS': '0x0', 14:50:33 INFO - 'XPC_SERVICE_NAME': '0', 14:50:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:50:33 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-RDr_Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:50:33 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)) 14:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:50:33 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-ZkeeQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:50:33 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)) 14:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:50:33 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-bmDMdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:50:33 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)) 14:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:50:33 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-fS08Af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:50:33 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)) 14:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:50:33 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-IUsqd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:50:33 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)) 14:50:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:50:33 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-A6nIXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:50:34 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)) 14:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:50:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-FBDteK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:50:34 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)) 14:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:50:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-B1ZMye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:50:34 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)) 14:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:50:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-dfRmlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:50:34 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)) 14:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:50:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-BSmNqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:50:34 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)) 14:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:50:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-cEY5dI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:50:34 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)) 14:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:50:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-9PO7el-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:50:34 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)) 14:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:50:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-b1dS25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:50:34 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)) 14:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:50:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-i3LH4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:50:34 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)) 14:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:50:34 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-8jN30N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:50:35 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)) 14:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:50:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-axItha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:50:35 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)) 14:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:50:35 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-CdpeG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:50:35 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)) 14:50:35 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)) 14:50:35 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)) 14:50:35 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)) 14:50:35 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)) 14:50:35 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)) 14:50:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:50:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:50:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:50:38 INFO - Downloading blessings-1.6.tar.gz 14:50:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:50:38 INFO - Installing collected packages: blessings 14:50:38 INFO - Running setup.py install for blessings 14:50:38 INFO - Successfully installed blessings 14:50:38 INFO - Cleaning up... 14:50:38 INFO - Return code: 0 14:50:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:50:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:50:38 INFO - Reading from file tmpfile_stdout 14:50:38 INFO - Current package versions: 14:50:38 INFO - blessings == 1.6 14:50:38 INFO - blobuploader == 1.2.4 14:50:38 INFO - docopt == 0.6.1 14:50:38 INFO - functools32 == 3.2.3-2 14:50:38 INFO - jsonschema == 2.5.1 14:50:38 INFO - manifestparser == 1.1 14:50:38 INFO - mozInstall == 1.12 14:50:38 INFO - mozcrash == 0.17 14:50:38 INFO - mozdebug == 0.1 14:50:38 INFO - mozdevice == 0.48 14:50:38 INFO - mozfile == 1.2 14:50:38 INFO - mozhttpd == 0.7 14:50:38 INFO - mozinfo == 0.9 14:50:38 INFO - mozleak == 0.1 14:50:38 INFO - mozlog == 3.4 14:50:38 INFO - moznetwork == 0.27 14:50:38 INFO - mozprocess == 0.23 14:50:38 INFO - mozprofile == 0.28 14:50:38 INFO - mozrunner == 6.12 14:50:38 INFO - mozscreenshot == 0.1 14:50:38 INFO - mozsystemmonitor == 0.3 14:50:38 INFO - moztest == 0.7 14:50:38 INFO - mozversion == 1.4 14:50:38 INFO - psutil == 3.1.1 14:50:38 INFO - requests == 1.2.3 14:50:38 INFO - wsgiref == 0.1.2 14:50:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:50:38 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')]} 14:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:39 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')]} 14:50:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:39 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 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:39 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 14:50:39 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 14:50:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:39 INFO - 'HOME': '/Users/cltbld', 14:50:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:39 INFO - 'LOGNAME': 'cltbld', 14:50:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:39 INFO - 'MOZ_NO_REMOTE': '1', 14:50:39 INFO - 'NO_EM_RESTART': '1', 14:50:39 INFO - 'PAGER': '/bin/cat', 14:50:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:39 INFO - 'PWD': '/builds/slave/test', 14:50:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:39 INFO - 'SHELL': '/bin/bash', 14:50:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:39 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:39 INFO - 'USER': 'cltbld', 14:50:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:39 INFO - 'XPC_FLAGS': '0x0', 14:50:39 INFO - 'XPC_SERVICE_NAME': '0', 14:50:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:39 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)) 14:50:39 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)) 14:50:39 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)) 14:50:39 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)) 14:50:39 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)) 14:50:39 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)) 14:50:39 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)) 14:50:39 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)) 14:50:39 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)) 14:50:39 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)) 14:50:39 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)) 14:50:39 INFO - Cleaning up... 14:50:39 INFO - Return code: 0 14:50:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:50:39 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')]} 14:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:50:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:39 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')]} 14:50:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:50:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:50:39 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 0x106fec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f62570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c15a7e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, '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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 14:50:39 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 14:50:39 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 14:50:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:50:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:50:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:50:39 INFO - 'HOME': '/Users/cltbld', 14:50:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:50:39 INFO - 'LOGNAME': 'cltbld', 14:50:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:50:39 INFO - 'MOZ_NO_REMOTE': '1', 14:50:39 INFO - 'NO_EM_RESTART': '1', 14:50:39 INFO - 'PAGER': '/bin/cat', 14:50:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:50:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:50:39 INFO - 'PWD': '/builds/slave/test', 14:50:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:50:39 INFO - 'SHELL': '/bin/bash', 14:50:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:50:39 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:50:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:50:39 INFO - 'USER': 'cltbld', 14:50:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:50:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:50:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:50:39 INFO - 'XPC_FLAGS': '0x0', 14:50:39 INFO - 'XPC_SERVICE_NAME': '0', 14:50:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:50:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:50:39 INFO - Cleaning up... 14:50:39 INFO - Return code: 0 14:50:39 INFO - Running post-action listener: _resource_record_post_action 14:50:39 INFO - Running post-action listener: _start_resource_monitoring 14:50:39 INFO - Starting resource monitoring. 14:50:39 INFO - [mozharness: 2016-10-14 21:50:39.719626Z] Finished create-virtualenv step (success) 14:50:39 INFO - [mozharness: 2016-10-14 21:50:39.720650Z] Running install step. 14:50:39 INFO - Running pre-action listener: _resource_record_pre_action 14:50:39 INFO - Running main action method: install 14:50:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:50:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:50:39 INFO - Reading from file tmpfile_stdout 14:50:39 INFO - Detecting whether we're running mozinstall >=1.0... 14:50:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:50:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:50:40 INFO - Reading from file tmpfile_stdout 14:50:40 INFO - Output received: 14:50:40 INFO - Usage: mozinstall [options] installer 14:50:40 INFO - Options: 14:50:40 INFO - -h, --help show this help message and exit 14:50:40 INFO - -d DEST, --destination=DEST 14:50:40 INFO - Directory to install application into. [default: 14:50:40 INFO - "/builds/slave/test"] 14:50:40 INFO - --app=APP Application being installed. [default: firefox] 14:50:40 INFO - mkdir: /builds/slave/test/build/application 14:50:40 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'] 14:50:40 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 14:51:14 INFO - Reading from file tmpfile_stdout 14:51:14 INFO - Output received: 14:51:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:51:14 INFO - Running post-action listener: _resource_record_post_action 14:51:14 INFO - [mozharness: 2016-10-14 21:51:14.835287Z] Finished install step (success) 14:51:14 INFO - [mozharness: 2016-10-14 21:51:14.835440Z] Running run-tests step. 14:51:14 INFO - Running pre-action listener: _resource_record_pre_action 14:51:14 INFO - Running main action method: run_tests 14:51:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:51:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:51:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:51:14 INFO - 2016-10-14 14:51:14.849 screenresolution[1932:12591] starting screenresolution argv=screenresolution get 14:51:14 INFO - 2016-10-14 14:51:14.852 screenresolution[1932:12591] Display 0: 1600x1200x32@60 14:51:14 INFO - 2016-10-14 14:51:14.858 screenresolution[1933:12593] starting screenresolution argv=screenresolution list 14:51:14 INFO - Available Modes on Display 0 14:51:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:51:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:51:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:51:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:51:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:51:15 INFO - Intel Iris: 14:51:15 INFO - Chipset Model: Intel Iris 14:51:15 INFO - Type: GPU 14:51:15 INFO - Bus: Built-In 14:51:15 INFO - VRAM (Dynamic, Max): 1536 MB 14:51:15 INFO - Vendor: Intel (0x8086) 14:51:15 INFO - Device ID: 0x0a2e 14:51:15 INFO - Revision ID: 0x0009 14:51:15 INFO - Displays: 14:51:15 INFO - Display: 14:51:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:51:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:51:15 INFO - Display Serial Number: 1600x1200 60 14:51:15 INFO - Main Display: Yes 14:51:15 INFO - Mirror: Off 14:51:15 INFO - Online: Yes 14:51:15 INFO - Rotation: Supported 14:51:15 INFO - Return code: 0 14:51:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:51:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:51:15 INFO - Python 2.7.3 14:51:15 INFO - Return code: 0 14:51:15 INFO - grabbing minidump binary from tooltool 14:51:15 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')]} 14:51:15 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 0x106e00d30>, '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 0x106c26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106df8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:51:15 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 14:51:15 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 14:51:15 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 14:51:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:51:15 INFO - Return code: 0 14:51:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:51:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:51:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:51:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:51:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:51:15 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/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0158', '--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 14:51:15 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/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0158 --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 14:51:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IFHOfYBjy5/Render', 14:51:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:51:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:51:15 INFO - 'HOME': '/Users/cltbld', 14:51:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:51:15 INFO - 'LOGNAME': 'cltbld', 14:51:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:51:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:51:15 INFO - 'MOZ_NO_REMOTE': '1', 14:51:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:51:15 INFO - 'NO_EM_RESTART': '1', 14:51:15 INFO - 'PAGER': '/bin/cat', 14:51:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:51:15 INFO - 'PWD': '/builds/slave/test', 14:51:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:51:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:51:15 INFO - 'SHELL': '/bin/bash', 14:51:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sRcwcNWX5I/Listeners', 14:51:15 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 14:51:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:51:15 INFO - 'USER': 'cltbld', 14:51:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:51:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:51:15 INFO - 'XPC_FLAGS': '0x0', 14:51:15 INFO - 'XPC_SERVICE_NAME': '0', 14:51:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:51:15 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/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0158', '--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 14:51:15 INFO - mozversion application_buildid: 20161014122035 14:51:15 INFO - mozversion application_changeset: 8ca6edde188aa0bf9ac707b5e1e7b95792e413ff 14:51:15 INFO - mozversion application_display_name: Nightly 14:51:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:51:15 INFO - mozversion application_name: Firefox 14:51:15 INFO - mozversion application_remotingname: firefox 14:51:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:51:15 INFO - mozversion application_vendor: Mozilla 14:51:15 INFO - mozversion application_version: 52.0a1 14:51:15 INFO - mozversion platform_buildid: 20161014122035 14:51:15 INFO - mozversion platform_changeset: 8ca6edde188aa0bf9ac707b5e1e7b95792e413ff 14:51:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:51:15 INFO - mozversion platform_version: 52.0a1 14:51:15 INFO - using testdate: 1476481875 14:51:15 INFO - actual date: 1476481875 14:51:15 INFO - starting webserver on 'localhost:52120' 14:51:15 INFO - SUITE-START | Running 2 tests 14:51:15 INFO - TEST-START | dromaeo_css 14:51:15 INFO - operating with platform_type : mac_ 14:51:15 INFO - Initialising browser for dromaeo_css test... 14:51:15 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpDgZ4JP/profile http://localhost:52120/getInfo.html) 14:51:17 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 14:51:17 INFO - PROCESS | 1943 | colorDepth:24 14:51:17 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 14:51:17 INFO - PROCESS | 1943 | __metrics 14:51:17 INFO - PROCESS | 1943 | 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" 14:51:17 INFO - PROCESS | 1943 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:51:18 INFO - TEST-INFO | 1943: exit 0 14:51:18 INFO - Browser initialized. 14:51:18 INFO - Running cycle 1/1 for dromaeo_css test... 14:51:18 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpDgZ4JP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:51:30 INFO - PROCESS | 1945 | RSS: Main: 240873472 14:51:30 INFO - PROCESS | 1945 | 14:52:21 INFO - PROCESS | 1945 | 1476481941892 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] 14:54:36 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52120/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52120/tests/dromaeo/cssquery-ext.html) 14:54:37 INFO - PROCESS | 1945 | RSS: Main: 381878272 14:54:37 INFO - PROCESS | 1945 | 14:57:40 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52120/tests/dromaeo/cssquery-ext.html (next: http://localhost:52120/tests/dromaeo/cssquery-jquery.html) 14:57:41 INFO - PROCESS | 1945 | RSS: Main: 392146944 14:57:41 INFO - PROCESS | 1945 | 15:00:43 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52120/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52120/tests/dromaeo/cssquery-mootools.html) 15:00:44 INFO - PROCESS | 1945 | RSS: Main: 339546112 15:00:44 INFO - PROCESS | 1945 | 15:03:46 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52120/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52120/tests/dromaeo/cssquery-prototype.html) 15:03:47 INFO - PROCESS | 1945 | RSS: Main: 337793024 15:03:47 INFO - PROCESS | 1945 | 15:06:50 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52120/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52120/tests/dromaeo/cssquery-yui.html) 15:06:51 INFO - PROCESS | 1945 | RSS: Main: 333488128 15:06:51 INFO - PROCESS | 1945 | 15:09:54 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52120/tests/dromaeo/cssquery-yui.html (next: http://localhost:52120/tests/dromaeo/cssquery-dojo.html) 15:09:54 INFO - PROCESS | 1945 | RSS: Main: 396664832 15:09:54 INFO - PROCESS | 1945 | 15:09:54 INFO - PROCESS | 1945 | __start_tp_report 15:09:54 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 15:09:54 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 15:09:54 INFO - PROCESS | 1945 | |i|pagename|runs| 15:09:54 INFO - PROCESS | 1945 | |0;dojo.html;3304.801711897286;3317.568716066911;3345.2807646356027;3353.4131526982756;3400.642932492088;5551.833444996657;5651.095824668048;5670.943290567093;5674.2623458950275;5696.800611978581;5629.296337957755;5634.4928956393915;5721.942780572193;5751.539876809856;5768.336641286253;5059.671121377117;5105;5123.308353372303;5182.222666600002;5188.4811518848;5256.5794736421185;5268.209768534713;5284.180951952453;5296.417394086667;5335.093034184179;5117.360329958755;5263.26314315996;5264.15773476242;5280.656757310781;5332.840014799563;5173.456787037382;5182.818601348935;5219.791208351661;5239.978204250173;5256.500632439912;4285.678574106923;5147.639665223436;5197.194434862602;5209.453007434203;5251.737413129366;5631.2397826293745;5681.687507187103;5715.942840571624;5724.084146536534;5755.68343741094;6046.3349031606485;6092.7258273377365;6135.355787642323;6137.079438084323;6192.225971753531;6218.657973811439;6223.7821676241565;6282.811515654537;6291.056341548759;6293.9370606293805;5744.540436765049;5744.597878148489;5746.360008359803;5747.770089196469;5796.652200867961;6208.968955155286;6245.437910588069;6270.122182894398;6305.653189074553;6324.936750632527;5953.28560572734;6072.271327440647;6085.117657939574;6087.269527656621;6113.897846251784;6222.564420490613;6231.626102433778;6243.625382476975;6249.281332646713;6252.5935814171935;6108.847278818065;6148.1085242639565;6152.3540028297275;6170.352113028157;6175;6006.159137720722;6068.514518838482;6070.119832624245;6108.3280839107665;6136.079588061826;4813.542713442217;4839.99999999993;4868.561829435368;4876.4879687633;4882.975585122052;4299.54854740254;4340.60934515895;4449.377087207794;4458.108378324348;4458.152950939311;5654.604177707521;5664.54683625309;5667.801626943037;5669.291338582677;5671.319441667027;6144.787876364505;6149.262088549313;6150.600210986271;6160.829442405929;6178;5647.435256474319;5671.9716401418555;5673.574481913873;5681.0910254359105;5685.1756495307945;5342.813001544927;5343.171808369709;5354.973225133849;5368.570514358842;5374.086405311028;6286.717097730613;6286.842828929313;6290.370962903673;6307.463865571386;6322.589031712924;6084.178635884099;6116.694165291628;6171.59884607499;6266.874662506815;6295.307516173309;6234.9688251558455;6288.151099601495;6304.71628776697;6318.210223722035;6321.96839015802;5714.7998920225555;6154.753809847556;6160.168377269011;6174.25908890936;6176.752929882754;6112.755489780359;6236.75052997875;6240.594361366497;6263.843403914939;6266.122742816101;4955.40535135786;5968.045112781934;6023.3073196582955;6045.304789949212;6110.7861224856915;6023.879522409439;6040.6677632730625;6041.516678665608;6047.455728984237;6122.78570250057;5818.33089194748;5822.737976791139;5852.151438041375;5878.500327472042;5896.439838215363;4543.886402839956;5844.620099693507;5855.707214639337;6050.485708714808;6071.848203794941;5802.332731735735;5849.151872978309;5855.2973643163095;5914.290285165807;5962.046072628359 15:09:54 INFO - PROCESS | 1945 | |1;ext.html;451.2441660219133;465.2021782642767;478.26347424965553;535.2559941681064;535.908895487767;29787;38488;39718.801405993;39843.80078099609;42028.78985605075;40812.59187408125;41110;41135.58864411355;41337.58662413375;41356.793216033875;9280.489573073479;9453.243740500779;9462.101100395486;9473.478958657272;9477.336586438953;8598.79616853641;8823.77940551485;9472.81054378912;9473.81052378952;9481;8126.78098285259;9472.147506724394;9483.81032379352;9492.905070949271;9515.762105947337;9416.81166376672;9466.857997130048;9486.19367353769;9498.19265362445;9509.667161649344;7931.206879312081;7979.401544884099;9255.305852061123;9300;9321.114494123047;39407.21185576301;39642.603573964465;39675;39702.60297397018;39778.80110599457;3258.96741032589;3299.7525185611175;3554.5117624908357;3788.6211378862167;3989.3617021276746;29161.56257656137;29525.40949181026;29633.407331853243;31005.379892402252;31076.73694262115;3139.2465808206093;3176.1241702849775;3243.1405677495245;3254.495553189259;3324.268660894599;457517;458461;458490;459330.70334648446;469949;54335;54678.45321546813;55032.724836375564;55265.72367138219;55332.72333638306;58809.5297617563;58816.70591647014;58839;58912.11631825526;58925.41074589285;15924.76112858308;16125.596860078593;16132.112733799519;16141.515754527383;16144;12574.497020119094;12667.93666031664;12687.302198378993;12688.6827829305;12689.746205075848;14631.487897923735;14846.331915063847;14913;14926.701465970837;15015.774763378558;6805.727770889208;6918.342757438136;6954.40887524556;6999.005283494282;7044.471664625174;911.4759013567225;1019.1948360795037;1037.4449669426972;1056.9312994655322;1072.1369297715257;23647.172348967702;28729.425411491655;29265.56101658477;30001.699983000326;30145.246368840955;962.8748262725941;1070.2668671959732;1087.1683162380784;1120.675004248775;1213.9210951288137;12300.938495307466;13269.601911942656;15256.932014759082;15403.845961540464;15632.4528641497;610574;613681;616607;617336;618288;55586.16620750692;55760;55776;55809.720951394986;55959.160612590844;1228.1893949993141;1281.628327784932;1314.1983390132011;1369.6851023018014;1416.1377788208883;29255.707442925297;30297.93957211487;30753.077407677807;30770.846145769126;31093.378132436774;46207.53792462032;46493;46620.83447913829;46721;46770.53229467662;521155;524886;525222;526404;527050;37119.814400927826;37553.6244637561;37832.05419864525;38006.80996594999;38034.61965380421;45302.86742831456;45327;46030;46327;47009;37328;37485.4377184337;37823.621763783114;38578.421323680726;38864;37294.81352593219;38024.696287570296;38245.04387390338;38623.42064869085;39033.2193356137;36944.445833311984;37164.44253336148;37789;37905;38053;30450.88912996717;33016.84441044552;33560.83219583886;33621.82323618662;33672.663273366954;24201.75798241995;24388.02447902135;24660.50678986446;24978.250652480456;25363.61954570646 15:09:54 INFO - PROCESS | 1945 | |2;jquery.html;342.4161804123969;364.5497810204397;365.63619198897095;375.4143536083709;396.5697218517908;14950.77573836392;15163.469278575252;16318.673626527478;20695.793042069574;21147.682784758203;20771.480712982182;21264;21840.126394944222;22079.779202207974;22267.44331391716;5623.353314368838;5625.831225063244;5886.558508111887;5917.082852157922;5918.2898052233795;5839.240898683186;6013.939860601382;6014.278286605591;6018.307894592134;6036.667983260919;6001.87996240075;6210.627362358273;6573.276939536647;6599.373059559334;6682.5656332338485;5962.731677074542;6148.200733904609;6550.377714117151;6588.341165883421;6609.140811694497;5804.448577385142;5808.1578171165365;5810.041343178371;5813.505852002542;5813.505852002585;12783.296918669563;13023.804642930363;13071.54249601269;13112.27882466464;13274.336283185801;2144.699742036101;2166.6425039868404;2250.9549809003893;2311.90752369905;2804.621376114219;7851.646675899598;8827.823443531093;9784.95107524465;10344.434927691926;10555.73610659732;1826.3607737291975;1840.7699037620298;1870.5043163561668;1876.9249230030782;1891.186789680437;112183;118065;118865;119363.99999999913;119606;10058.145057670034;11221;11228.606998755004;11341;11611.303321800719;13441.260730659907;14516.854831451761;14532.418703251751;14534.345954432076;14557.272136393138;6891;8248.13394593559;8422.831543369099;8568.828623427496;8642.78393040179;7002.159740831133;8232.300254478316;8300.916990830014;8322.70870519529;8520.616572254263;4400.141972315368;4472.843450479218;4526.275795872644;4526.502084770673;4568.862934111916;1827.8629102817076;1885.698288273897;1888.7450194223604;1892.7823300320365;1893.384649988759;842.7850898020965;864.3636907432307;902.625410454654;1007.0684616729496;1016.9644062457778;20344.18623255083;21712.221003469334;21827;21831.31430385642;21867.61405760993;652.4975768658106;853.5063638632394;941.1012483078644;959.8896126945351;968.8062387522524;10772.030517253485;10922.071623912056;11253.943730281297;11467;11471.254368466023;561030;580126;583820;595268;599781;121142;122469.38765306116;124258;126178.36910815387;135317;976.8876579193342;1003.3377970539555;1046.1369370269558;1047.2459828923163;1048.7849990751877;18110;18520.81479185191;18563.071846407896;18630.627387452445;19116.713249300992;52299.47700522946;52959.20561191508;53910.73044634909;54363.72818135884;55067;70774;72961.15024471628;72982;73247.53504929978;81147;6446.000869865178;6977.686004129725;7986.401019923645;8012.5192488450875;8075.838483230185;10428.791424171626;12680.365981700694;12778.74442511126;13234.536791212262;13262.867371326163;10754.89245107539;10804.351738895692;11051.889481105087;11060.225784195029;11131.777364452828;5002.499750024968;7428.925710743039;9339.486328252011;11000.559977601126;11115.777264500737;9042.276617870422;10958.342499450058;11438.599649012245;11607.941960290145;11708.121890858049;7932.620213935911;7950.7217247396065;7998.200179981955;8024.197580242163;8039.226466868921;9911.256655750993;10826.350418974887;10988.285761425483;11134;11221.046211071824 15:09:54 INFO - PROCESS | 1945 | |3;mootools.html;1201.2672269915356;1535.9539213823582;1571.787808645833;1795.6318954614305;1943.9708404373937;7678.577678227695;8132.634031468583;8433;8708.04211536732;8717.86923196151;8544.359173062014;8647.740567782977;8655.69705060323;8659;8660.696875609356;2320.8607483550954;2357.787799098081;2358.351453350334;2362.9291121266388;2365.2431222008963;2297.701298831158;2335.4628435459854;2344.2381226101584;2347.7769611886843;2351.071326825903;1950.765908091031;2302.286291249718;2332.3236261484235;2335.6963594732747;2342.063174730113;2292.083166733303;2300.919467818627;2313.687652166953;2319.7100362454694;2324.5002324500315;2269.0923630547913;2311.6070268054305;2315.4211447138214;2319.408550819547;2327.51122264323;8486.32109431244;8627.741167764914;8697.869531957025;8699.652013919436;8703.47779133254;5157.613179011589;5377.973110134424;5390.110631745757;5391.703456309901;5404.729763511808;3585.444256140276;3586.982065089684;3662.029562166028;3667.394879844849;3668.211334563082;1037.3153718545723;1043.4678314059809;1050.5167622893478;1054.6361505280665;1068.439069488517;10914.617988370368;10964.835527467094;11054.834177487344;11057.170712196617;11062.28095173803;4207.537170911197;4329.0259691569145;4443.466783985942;4500.729956202638;4516.006478574708;7423.740169094164;8773.561321933877;8773.692920747872;8785.604647790864;8794.164554367326;5047.091523525801;5049.444561098277;5051.520105589963;5052.469490703496;5053.494650534991;4180.159823911235;4390.714603550759;4469.9553004469535;4566.566176213254;4718.292256161603;3543.8759643412873;3858.459815625815;3864.652181303696;3868;3870.148567315186;1116.5812820192427;1138.9487473063732;1143.3654321851452;1158.264502041193;1159.3913195572222;892.3084609427746;921.4517362169498;923.2752289452748;925.6716611662287;960.078324808192;5730.455606717355;5797.505816937107;5998;6007.789727359522;6060.878782424327;412.12012353625227;427.8203154675044;430.1847998043752;432.10769760444583;435.31655301176846;3868.6711629511806;3994.580569040267;4004.6395824375945;4019.618136277049;4022.416749571296;9851.014898510235;10300.484975751182;10598.735031624272;10709.625163119254;10741.462926853783;10017.499125043718;10388.37669739818;10392.37645741258;10498.947505262577;10528.842067368996;7570.848583028309;7840;7953.761387158279;8000.479968802009;8027.07688615805;5582.581306402118;5823.7379317931645;5931.961906666403;5999.940000599938;6083.6045657032155;9504.23966082698;9887.752806179904;9912.35569687968;9938.552765125442;9942.602295908082;9122.5438728062;9426.340156189;9892.802143956937;9993;10009.949950250202;7518.8496230074;7782.844343113219;7895.921040789748;8014.83970320602;8102.635381407968;8063.314618257514;8129.430939834155;8154.306883914934;8185.38609604294;8202.097769245498;7377.741779037708;7420.554766713799;7442.553446793209;7489;7500;7187.676554554953;7249.601271930094;7273.745418910313;7388.371988380833;7395.963020184865;6151.231096112986;6718.025886246369;6788.219354774066;6850.075239842757;6944.791656250321;4872.610191184767;4975.577075948585;5006;5030;5168.302279192411;4324.9135017300105;5055.799123156607;5062;5071.290019397361;5171.069207542679 15:09:54 INFO - PROCESS | 1945 | |4;prototype.html;299.66437589899317;301.29496977073643;308.87181819544884;314.0750489807518;316.77250653716845;5003.824866129687;5290.2593636890815;5360.4639536046425;5390.460953904608;5416.945830541694;3876.9806150969275;4493.393391892094;5005.324281222034;5066.5693416059585;5191.117510023296;4031.5162180538377;4033.1933613277315;4068.2677118118727;4073.5111786585503;4086.652634526054;3659.908502287451;4043.3934909763475;4049.2508885856064;4073.287174744423;4093.856715014975;3270.394976929264;3391.389549881033;3718.814059297038;4011;4040.797960101998;3891;4006.959930400688;4022.175454031931;4044.3933409988445;4070.618688067484;2869.727375899307;3449.724022078228;3456.94814577779;3457.792532448061;3468.514407982884;5005.299258103868;5157.7936882524655;5227.03986005248;5243.816466423695;5251.081060814342;3849.538055433338;4554.43069616298;4578.862634120982;4597.758580200951;4602.286645569909;4769.403824521934;5090;5553.666779993173;5663.1505274208785;5667.744951477193;3770.245950809849;3773.6037716039696;3790.2608991246716;3794.7912864792424;3808.90477738056;5339.038972984822;5501.779928802843;5644.830655080313;5882.147088672162;5890.734916928748;3202.439573074703;3242.9460425361854;3578.874739384109;3721.404575267944;3835;3420.8369154487596;3508.4211105167874;3533.4876442916147;3745.9250814983548;3813.7521061130938;3890.455336252927;3965.6827453803626;4309.41822853917;4578.427696537933;4601.401817763669;5631.605787594911;5650.406707295757;5698.202251684767;5711.885762284731;5727.656340619576;1168.3690806964314;1243.7313939781372;1315.2963164706837;1336.946522139123;1356.816829727974;739.7965559471188;741;832.2592892325835;836.3189988123793;856.1823458597025;4125.731827431207;4451.933221001687;4534.841280555164;4542.454905411372;4545.0909818036525;4595.379623750818;4641.3734145889875;4910.74775932135;4929.408470983505;4944.961558072773;3356;4119.835206591702;4346.456692913386;4554.954450455453;4557.1087998480925;2425.229582404854;2520.508500842318;2540.911068112607;2553.7446255374316;2554.5274124287066;4432.889177770581;4714.339992401066;4803.951960480351;4935.432425271068;4969.35398398206;4158.707199680826;4232.407462955189;4773.427188737373;4837.274408838703;4931.950680493221;4572.193983376547;4615.23848564983;4897.3878265216845;4898.191798353232;4918.606511479073;4288.506821715543;4663.945115949059;4735.313379559876;4770.761461926821;4772.3557319761385;4507.57178068083;4720.527947205252;4810.374651295309;5172.612054095883;5652.0674088774895;4501.572350626685;5747.971260143672;6062.42406971337;6076.483498902643;6111.480524155497;3199.4560924643333;3865.96134038656;3874.18642085154;3888.6111388860886;3891.7470364427354;4924.49802810132;5388.622796404371;5776.855578610568;5800.419958004166;5836.212111364912;5629.718514074362;5643.294588176478;5649.079313033589;5666.943330566642;5682.318121825408;5053.8881446081805;5334.919976200283;5612.803551875665;5645.887082258249;5686.43135686428;5226.425093239664;5321.866953326198;5516.310461192351;5563.415841336601;5568.025595520849;5389.466950533243;5523.447655234444;5524.254225679482;5676.183621241164;5696.974544582015;4577.931331030104;4898.403845961646;5251.002309561171;5280.3663560373;5511.200875872896 15:09:54 INFO - PROCESS | 1945 | |5;yui.html;705.2031204738645;753.6194221917931;806.8830019647147;828.6022709099632;842.6294820717128;15298.69402611947;15818.76271855923;15869.76195357069;15879.92060039701;15887.364505419784;14098.788518172236;14582.635434114152;14831.55505334839;14843.851561484382;14870.405183792634;878.3631866896512;901.1214066285352;901.7249738829654;902.4449963272573;902.8284625921087;701.3687681087011;719.8920161975693;730.9963450182742;871.1462766488845;894.8791913091746;963.3786207895903;968.5931908598405;969.1277849935079;970.1365784451843;974.2449601558814;924.7179610218847;925.4539821505344;941.9952900235523;965.097633712474;967.6709918627665;538.1148011521026;548.9121740521538;675.0448115915973;693.0989713372595;709.5387997801419;15263.923680381527;15385.923070384688;15391.615209619737;15394;15404.922975385163;12448.937755311255;13722.862771372258;13913.443462261497;14014;14046.087004344787;12469;12569.183003104768;12594.181378210478;12642.241465512006;13165.53920612774;4403.207422663919;4430.712003719748;4483.417155769762;4525.751083690396;4566.657500687462;116501.41749291199;116632;118217;120046.3997680006;121005.78994210034;10785.730356741144;10906.672799816019;10911.83632245517;10978.12175026012;11414.828777568344;22237.332880013626;22299;22312.107515699514;22322.441938951655;22333.330000100024;6816.693248803716;6934.4452443804375;7833.960830195927;7962.920370796333;7975.1626079262005;4325;4363.694541382073;4380.888090298107;4452.398926144993;4566.086782643484;3402.6427225141006;3516.7186625069935;3522.5772907251294;3523.9823800881345;3529.770564913272;633.3065293503606;642.1683919324486;653.8757636049136;669.4276393683427;671.6104659297596;551.6331639459795;576.1933293389205;599.3976054065723;612.7640858269598;641.8170821315903;7816.49192802466;7822.843543129219;8920.73237802867;8960.115098713091;8968.192862642394;520;546.9097598896217;558.3634656491603;571.1489880078653;573.1746285349084;4041.6160464755803;4380.737155770664;4394.274944634163;4450.46594408673;4515.616172625298;164278;164958.3504164967;166067;169220.1538992322;173184.1340793288;72556.63721681357;75238.62380688061;84938;113264;119572.40213798876;83140.58429708055;102074.48962755139;105608.47195763972;105748.99999999846;106538;9027.50348730826;9321.30090243221;9333.766655833659;9715.659951901649;9750.31747777677;57672.134917977106;57820;58169.70915145397;59181.81636367163;60978;59887;82550.17449825424;85767.5711621438;87377.12622873943;89093;12680.23918564889;12714.237145771283;12737.163723079364;13070.150440221356;13125.868741312464;14426.42294308258;14524.273786310854;14628.26858657084;14647.853521465075;14993;12899.871001289866;13202.603921882359;13203.07578469498;13462;13951.720965580429;13524;13721.794173087212;13801;13805.240711760547;13820.378082986088;12965.740685186433;13019.674508137372;13200.537981170613;13485.46058157702;13866.722665546835;9688.951555242178;9921.40471571708;11029.724256893322;11460.598879039193;11825.881741182478;8325.917630707969;10905;11143.554257829921;11295.774084518427;11378.203525753117 15:09:54 INFO - PROCESS | 1945 | __end_tp_report 15:09:54 INFO - PROCESS | 1945 | __start_cc_report 15:09:54 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,1434 15:09:54 INFO - PROCESS | 1945 | __end_cc_report 15:09:54 INFO - PROCESS | 1945 | __startTimestamp1476482994729__endTimestamp 15:09:54 INFO - PROCESS | 1945 | 15:09:54 INFO - PROCESS | 1945 | ------- Summary: start ------- 15:09:54 INFO - PROCESS | 1945 | Number of tests: 6 15:09:54 INFO - PROCESS | 1945 | 15:09:54 INFO - PROCESS | 1945 | [#0] dojo.html Cycles:165 Average:5688.14 Median:5849.15 stddev:642.89 (11.0%) stddev-sans-first:617.08 15:09:54 INFO - PROCESS | 1945 | Values: 3304.8 3317.6 3345.3 3353.4 3400.6 5551.8 5651.1 5670.9 5674.3 5696.8 5629.3 5634.5 5721.9 5751.5 5768.3 5059.7 5105.0 5123.3 5182.2 5188.5 5256.6 5268.2 5284.2 5296.4 5335.1 5117.4 5263.3 5264.2 5280.7 5332.8 5173.5 5182.8 5219.8 5240.0 5256.5 4285.7 5147.6 5197.2 5209.5 5251.7 5631.2 5681.7 5715.9 5724.1 5755.7 6046.3 6092.7 6135.4 6137.1 6192.2 6218.7 6223.8 6282.8 6291.1 6293.9 5744.5 5744.6 5746.4 5747.8 5796.7 6209.0 6245.4 6270.1 6305.7 6324.9 5953.3 6072.3 6085.1 6087.3 6113.9 6222.6 6231.6 6243.6 6249.3 6252.6 6108.8 6148.1 6152.4 6170.4 6175.0 6006.2 6068.5 6070.1 6108.3 6136.1 4813.5 4840.0 4868.6 4876.5 4883.0 4299.5 4340.6 4449.4 4458.1 4458.2 5654.6 5664.5 5667.8 5669.3 5671.3 6144.8 6149.3 6150.6 6160.8 6178.0 5647.4 5672.0 5673.6 5681.1 5685.2 5342.8 5343.2 5355.0 5368.6 5374.1 6286.7 6286.8 6290.4 6307.5 6322.6 6084.2 6116.7 6171.6 6266.9 6295.3 6235.0 6288.2 6304.7 6318.2 6322.0 5714.8 6154.8 6160.2 6174.3 6176.8 6112.8 6236.8 6240.6 6263.8 6266.1 4955.4 5968.0 6023.3 6045.3 6110.8 6023.9 6040.7 6041.5 6047.5 6122.8 5818.3 5822.7 5852.2 5878.5 5896.4 4543.9 5844.6 5855.7 6050.5 6071.8 5802.3 5849.2 5855.3 5914.3 5962.0 15:09:54 INFO - PROCESS | 1945 | 15:09:54 INFO - PROCESS | 1945 | [#1] ext.html Cycles:180 Average:66711.16 Median:39658.80 stddev:143411.73 (361.6%) stddev-sans-first:143727.75 15:09:54 INFO - PROCESS | 1945 | Values: 451.2 465.2 478.3 535.3 535.9 29787.0 38488.0 39718.8 39843.8 42028.8 40812.6 41110.0 41135.6 41337.6 41356.8 9280.5 9453.2 9462.1 9473.5 9477.3 8598.8 8823.8 9472.8 9473.8 9481.0 8126.8 9472.1 9483.8 9492.9 9515.8 9416.8 9466.9 9486.2 9498.2 9509.7 7931.2 7979.4 9255.3 9300.0 9321.1 39407.2 39642.6 39675.0 39702.6 39778.8 3259.0 3299.8 3554.5 3788.6 3989.4 29161.6 29525.4 29633.4 31005.4 31076.7 3139.2 3176.1 3243.1 3254.5 3324.3 457517.0 458461.0 458490.0 459330.7 469949.0 54335.0 54678.5 55032.7 55265.7 55332.7 58809.5 58816.7 58839.0 58912.1 58925.4 15924.8 16125.6 16132.1 16141.5 16144.0 12574.5 12667.9 12687.3 12688.7 12689.7 14631.5 14846.3 14913.0 14926.7 15015.8 6805.7 6918.3 6954.4 6999.0 7044.5 911.5 1019.2 1037.4 1056.9 1072.1 23647.2 28729.4 29265.6 30001.7 30145.2 962.9 1070.3 1087.2 1120.7 1213.9 12300.9 13269.6 15256.9 15403.8 15632.5 610574.0 613681.0 616607.0 617336.0 618288.0 55586.2 55760.0 55776.0 55809.7 55959.2 1228.2 1281.6 1314.2 1369.7 1416.1 29255.7 30297.9 30753.1 30770.8 31093.4 46207.5 46493.0 46620.8 46721.0 46770.5 521155.0 524886.0 525222.0 526404.0 527050.0 37119.8 37553.6 37832.1 38006.8 38034.6 45302.9 45327.0 46030.0 46327.0 47009.0 37328.0 37485.4 37823.6 38578.4 38864.0 37294.8 38024.7 38245.0 38623.4 39033.2 36944.4 37164.4 37789.0 37905.0 38053.0 30450.9 33016.8 33560.8 33621.8 33672.7 24201.8 24388.0 24660.5 24978.3 25363.6 15:09:54 INFO - PROCESS | 1945 | 15:09:54 INFO - PROCESS | 1945 | [#2] jquery.html Cycles:180 Average:33994.37 Median:2281.43 stddev:97617.55 (4278.8%) stddev-sans-first:97858.69 15:09:54 INFO - PROCESS | 1945 | Values: 342.4 364.5 365.6 375.4 396.6 14950.8 15163.5 16318.7 20695.8 21147.7 20771.5 21264.0 21840.1 22079.8 22267.4 5623.4 5625.8 5886.6 5917.1 5918.3 5839.2 6013.9 6014.3 6018.3 6036.7 6001.9 6210.6 6573.3 6599.4 6682.6 5962.7 6148.2 6550.4 6588.3 6609.1 5804.4 5808.2 5810.0 5813.5 5813.5 12783.3 13023.8 13071.5 13112.3 13274.3 2144.7 2166.6 2251.0 2311.9 2804.6 7851.6 8827.8 9785.0 10344.4 10555.7 1826.4 1840.8 1870.5 1876.9 1891.2 112183.0 118065.0 118865.0 119364.0 119606.0 10058.1 11221.0 11228.6 11341.0 11611.3 13441.3 14516.9 14532.4 14534.3 14557.3 6891.0 8248.1 8422.8 8568.8 8642.8 7002.2 8232.3 8300.9 8322.7 8520.6 4400.1 4472.8 4526.3 4526.5 4568.9 1827.9 1885.7 1888.7 1892.8 1893.4 842.8 864.4 902.6 1007.1 1017.0 20344.2 21712.2 21827.0 21831.3 21867.6 652.5 853.5 941.1 959.9 968.8 10772.0 10922.1 11253.9 11467.0 11471.3 561030.0 580126.0 583820.0 595268.0 599781.0 121142.0 122469.4 124258.0 126178.4 135317.0 976.9 1003.3 1046.1 1047.2 1048.8 18110.0 18520.8 18563.1 18630.6 19116.7 52299.5 52959.2 53910.7 54363.7 55067.0 70774.0 72961.2 72982.0 73247.5 81147.0 6446.0 6977.7 7986.4 8012.5 8075.8 10428.8 12680.4 12778.7 13234.5 13262.9 10754.9 10804.4 11051.9 11060.2 11131.8 5002.5 7428.9 9339.5 11000.6 11115.8 9042.3 10958.3 11438.6 11607.9 11708.1 7932.6 7950.7 7998.2 8024.2 8039.2 9911.3 10826.4 10988.3 11134.0 11221.0 15:09:54 INFO - PROCESS | 1945 | 15:09:54 INFO - PROCESS | 1945 | [#3] mootools.html Cycles:180 Average:5553.61 Median:5048.27 stddev:3094.73 (61.3%) stddev-sans-first:3086.13 15:09:54 INFO - PROCESS | 1945 | Values: 1201.3 1536.0 1571.8 1795.6 1944.0 7678.6 8132.6 8433.0 8708.0 8717.9 8544.4 8647.7 8655.7 8659.0 8660.7 2320.9 2357.8 2358.4 2362.9 2365.2 2297.7 2335.5 2344.2 2347.8 2351.1 1950.8 2302.3 2332.3 2335.7 2342.1 2292.1 2300.9 2313.7 2319.7 2324.5 2269.1 2311.6 2315.4 2319.4 2327.5 8486.3 8627.7 8697.9 8699.7 8703.5 5157.6 5378.0 5390.1 5391.7 5404.7 3585.4 3587.0 3662.0 3667.4 3668.2 1037.3 1043.5 1050.5 1054.6 1068.4 10914.6 10964.8 11054.8 11057.2 11062.3 4207.5 4329.0 4443.5 4500.7 4516.0 7423.7 8773.6 8773.7 8785.6 8794.2 5047.1 5049.4 5051.5 5052.5 5053.5 4180.2 4390.7 4470.0 4566.6 4718.3 3543.9 3858.5 3864.7 3868.0 3870.1 1116.6 1138.9 1143.4 1158.3 1159.4 892.3 921.5 923.3 925.7 960.1 5730.5 5797.5 5998.0 6007.8 6060.9 412.1 427.8 430.2 432.1 435.3 3868.7 3994.6 4004.6 4019.6 4022.4 9851.0 10300.5 10598.7 10709.6 10741.5 10017.5 10388.4 10392.4 10498.9 10528.8 7570.8 7840.0 7953.8 8000.5 8027.1 5582.6 5823.7 5932.0 5999.9 6083.6 9504.2 9887.8 9912.4 9938.6 9942.6 9122.5 9426.3 9892.8 9993.0 10009.9 7518.8 7782.8 7895.9 8014.8 8102.6 8063.3 8129.4 8154.3 8185.4 8202.1 7377.7 7420.6 7442.6 7489.0 7500.0 7187.7 7249.6 7273.7 7388.4 7396.0 6151.2 6718.0 6788.2 6850.1 6944.8 4872.6 4975.6 5006.0 5030.0 5168.3 4324.9 5055.8 5062.0 5071.3 5171.1 15:09:54 INFO - PROCESS | 1945 | 15:09:54 INFO - PROCESS | 1945 | [#4] prototype.html Cycles:180 Average:4302.42 Median:4608.76 stddev:1340.91 (29.1%) stddev-sans-first:1310.59 15:09:54 INFO - PROCESS | 1945 | Values: 299.7 301.3 308.9 314.1 316.8 5003.8 5290.3 5360.5 5390.5 5416.9 3877.0 4493.4 5005.3 5066.6 5191.1 4031.5 4033.2 4068.3 4073.5 4086.7 3659.9 4043.4 4049.3 4073.3 4093.9 3270.4 3391.4 3718.8 4011.0 4040.8 3891.0 4007.0 4022.2 4044.4 4070.6 2869.7 3449.7 3456.9 3457.8 3468.5 5005.3 5157.8 5227.0 5243.8 5251.1 3849.5 4554.4 4578.9 4597.8 4602.3 4769.4 5090.0 5553.7 5663.2 5667.7 3770.2 3773.6 3790.3 3794.8 3808.9 5339.0 5501.8 5644.8 5882.1 5890.7 3202.4 3242.9 3578.9 3721.4 3835.0 3420.8 3508.4 3533.5 3745.9 3813.8 3890.5 3965.7 4309.4 4578.4 4601.4 5631.6 5650.4 5698.2 5711.9 5727.7 1168.4 1243.7 1315.3 1336.9 1356.8 739.8 741.0 832.3 836.3 856.2 4125.7 4451.9 4534.8 4542.5 4545.1 4595.4 4641.4 4910.7 4929.4 4945.0 3356.0 4119.8 4346.5 4555.0 4557.1 2425.2 2520.5 2540.9 2553.7 2554.5 4432.9 4714.3 4804.0 4935.4 4969.4 4158.7 4232.4 4773.4 4837.3 4932.0 4572.2 4615.2 4897.4 4898.2 4918.6 4288.5 4663.9 4735.3 4770.8 4772.4 4507.6 4720.5 4810.4 5172.6 5652.1 4501.6 5748.0 6062.4 6076.5 6111.5 3199.5 3866.0 3874.2 3888.6 3891.7 4924.5 5388.6 5776.9 5800.4 5836.2 5629.7 5643.3 5649.1 5666.9 5682.3 5053.9 5334.9 5612.8 5645.9 5686.4 5226.4 5321.9 5516.3 5563.4 5568.0 5389.5 5523.4 5524.3 5676.2 5697.0 4577.9 4898.4 5251.0 5280.4 5511.2 15:09:54 INFO - PROCESS | 1945 | 15:09:54 INFO - PROCESS | 1945 | [#5] yui.html Cycles:180 Average:23976.02 Median:4372.22 stddev:38808.54 (887.6%) stddev-sans-first:38878.08 15:09:54 INFO - PROCESS | 1945 | Values: 705.2 753.6 806.9 828.6 842.6 15298.7 15818.8 15869.8 15879.9 15887.4 14098.8 14582.6 14831.6 14843.9 14870.4 878.4 901.1 901.7 902.4 902.8 701.4 719.9 731.0 871.1 894.9 963.4 968.6 969.1 970.1 974.2 924.7 925.5 942.0 965.1 967.7 538.1 548.9 675.0 693.1 709.5 15263.9 15385.9 15391.6 15394.0 15404.9 12448.9 13722.9 13913.4 14014.0 14046.1 12469.0 12569.2 12594.2 12642.2 13165.5 4403.2 4430.7 4483.4 4525.8 4566.7 116501.4 116632.0 118217.0 120046.4 121005.8 10785.7 10906.7 10911.8 10978.1 11414.8 22237.3 22299.0 22312.1 22322.4 22333.3 6816.7 6934.4 7834.0 7962.9 7975.2 4325.0 4363.7 4380.9 4452.4 4566.1 3402.6 3516.7 3522.6 3524.0 3529.8 633.3 642.2 653.9 669.4 671.6 551.6 576.2 599.4 612.8 641.8 7816.5 7822.8 8920.7 8960.1 8968.2 520.0 546.9 558.4 571.1 573.2 4041.6 4380.7 4394.3 4450.5 4515.6 164278.0 164958.4 166067.0 169220.2 173184.1 72556.6 75238.6 84938.0 113264.0 119572.4 83140.6 102074.5 105608.5 105749.0 106538.0 9027.5 9321.3 9333.8 9715.7 9750.3 57672.1 57820.0 58169.7 59181.8 60978.0 59887.0 82550.2 85767.6 87377.1 89093.0 12680.2 12714.2 12737.2 13070.2 13125.9 14426.4 14524.3 14628.3 14647.9 14993.0 12899.9 13202.6 13203.1 13462.0 13951.7 13524.0 13721.8 13801.0 13805.2 13820.4 12965.7 13019.7 13200.5 13485.5 13866.7 9689.0 9921.4 11029.7 11460.6 11825.9 8325.9 10905.0 11143.6 11295.8 11378.2 15:09:54 INFO - PROCESS | 1945 | -------- Summary: end -------- 15:09:54 INFO - PROCESS | 1945 | 15:09:55 INFO - TEST-INFO | 1945: exit 0 15:09:55 INFO - TEST-OK | dromaeo_css | took 1119960ms 15:09:55 INFO - TEST-START | kraken 15:09:55 INFO - operating with platform_type : mac_ 15:09:55 INFO - Initialising browser for kraken test... 15:09:55 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpkAAuFR/profile http://localhost:52120/getInfo.html) 15:09:57 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 15:09:57 INFO - PROCESS | 1949 | colorDepth:24 15:09:57 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 15:09:57 INFO - PROCESS | 1949 | __metrics 15:09:57 INFO - PROCESS | 1949 | 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" 15:09:57 INFO - PROCESS | 1949 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:09:58 INFO - TEST-INFO | 1949: exit 0 15:09:58 INFO - Browser initialized. 15:09:58 INFO - Running cycle 1/1 for kraken test... 15:09:58 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpkAAuFR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:10:10 INFO - PROCESS | 1950 | RSS: Main: 232382464 15:10:10 INFO - PROCESS | 1950 | 15:10:13 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52120/tests/kraken/driver.html?testName=audio-beat-detection) 15:10:14 INFO - PROCESS | 1950 | RSS: Main: 291180544 15:10:14 INFO - PROCESS | 1950 | 15:10:16 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52120/tests/kraken/driver.html?testName=audio-dft) 15:10:17 INFO - PROCESS | 1950 | RSS: Main: 292380672 15:10:17 INFO - PROCESS | 1950 | 15:10:20 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52120/tests/kraken/driver.html?testName=audio-fft) 15:10:21 INFO - PROCESS | 1950 | RSS: Main: 388505600 15:10:21 INFO - PROCESS | 1950 | 15:10:23 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52120/tests/kraken/driver.html?testName=audio-oscillator) 15:10:24 INFO - PROCESS | 1950 | RSS: Main: 290897920 15:10:24 INFO - PROCESS | 1950 | 15:10:26 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52120/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:10:26 INFO - PROCESS | 1950 | RSS: Main: 292298752 15:10:26 INFO - PROCESS | 1950 | 15:10:29 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52120/tests/kraken/driver.html?testName=imaging-darkroom) 15:10:30 INFO - PROCESS | 1950 | RSS: Main: 330047488 15:10:30 INFO - PROCESS | 1950 | 15:10:33 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52120/tests/kraken/driver.html?testName=imaging-desaturate) 15:10:33 INFO - PROCESS | 1950 | RSS: Main: 331292672 15:10:33 INFO - PROCESS | 1950 | 15:10:36 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52120/tests/kraken/driver.html?testName=json-parse-financial) 15:10:37 INFO - PROCESS | 1950 | RSS: Main: 326709248 15:10:37 INFO - PROCESS | 1950 | 15:10:38 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52120/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:10:39 INFO - PROCESS | 1950 | RSS: Main: 303579136 15:10:39 INFO - PROCESS | 1950 | 15:10:41 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52120/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:10:42 INFO - PROCESS | 1950 | RSS: Main: 307478528 15:10:42 INFO - PROCESS | 1950 | 15:10:44 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52120/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:10:44 INFO - PROCESS | 1950 | RSS: Main: 309514240 15:10:44 INFO - PROCESS | 1950 | 15:10:47 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52120/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:10:48 INFO - PROCESS | 1950 | RSS: Main: 319918080 15:10:48 INFO - PROCESS | 1950 | 15:10:51 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52120/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:10:52 INFO - PROCESS | 1950 | RSS: Main: 319696896 15:10:52 INFO - PROCESS | 1950 | 15:10:53 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52120/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52120/tests/kraken/driver.html?testName=ai-astar) 15:10:54 INFO - PROCESS | 1950 | RSS: Main: 363393024 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | __start_tp_report 15:10:54 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 15:10:54 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 15:10:54 INFO - PROCESS | 1950 | |i|pagename|runs| 15:10:54 INFO - PROCESS | 1950 | |0;ai-astar;103;120;163;159;113;112;111;113;112;111 15:10:54 INFO - PROCESS | 1950 | |1;audio-beat-detection;153;111;115;150;112;114;113;114;145;112 15:10:54 INFO - PROCESS | 1950 | |2;audio-dft;178;184;184;187;185;184;179;251;183;184 15:10:54 INFO - PROCESS | 1950 | |3;audio-fft;82;60;58;107;86;83;85;80;84;57 15:10:54 INFO - PROCESS | 1950 | |4;audio-oscillator;88;72;96;71;67;69;92;68;69;91 15:10:54 INFO - PROCESS | 1950 | |5;imaging-gaussian-blur;89;87;90;86;88;85;90;87;88;85 15:10:54 INFO - PROCESS | 1950 | |6;imaging-darkroom;140;150;118;96;96;95;96;96;96;94 15:10:54 INFO - PROCESS | 1950 | |7;imaging-desaturate;81;81;79;83;82;83;85;86;81;88 15:10:54 INFO - PROCESS | 1950 | |8;json-parse-financial;51;50;54;54;55;57;57;55;55;55 15:10:54 INFO - PROCESS | 1950 | |9;json-stringify-tinderbox;66;73;74;77;73;69;70;71;70;72 15:10:54 INFO - PROCESS | 1950 | |10;stanford-crypto-aes;88;90;87;87;90;86;89;86;87;87 15:10:54 INFO - PROCESS | 1950 | |11;stanford-crypto-ccm;162;149;162;166;140;145;148;149;187;146 15:10:54 INFO - PROCESS | 1950 | |12;stanford-crypto-pbkdf2;173;173;174;171;174;171;169;170;170;174 15:10:54 INFO - PROCESS | 1950 | |13;stanford-crypto-sha256-iterative;68;71;77;92;89;89;73;69;67;68 15:10:54 INFO - PROCESS | 1950 | __end_tp_report 15:10:54 INFO - PROCESS | 1950 | __start_cc_report 15:10:54 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,1997 15:10:54 INFO - PROCESS | 1950 | __end_cc_report 15:10:54 INFO - PROCESS | 1950 | __startTimestamp1476483054223__endTimestamp 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | ------- Summary: start ------- 15:10:54 INFO - PROCESS | 1950 | Number of tests: 14 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#0] ai-astar Cycles:10 Average:121.70 Median:113.00 stddev:21.13 (18.7%) stddev-sans-first:21.30 15:10:54 INFO - PROCESS | 1950 | Values: 103.0 120.0 163.0 159.0 113.0 112.0 111.0 113.0 112.0 111.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:114.50 stddev:17.69 (15.5%) stddev-sans-first:15.31 15:10:54 INFO - PROCESS | 1950 | Values: 153.0 111.0 115.0 150.0 112.0 114.0 113.0 114.0 145.0 112.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#2] audio-dft Cycles:10 Average:189.90 Median:184.00 stddev:21.64 (11.8%) stddev-sans-first:22.52 15:10:54 INFO - PROCESS | 1950 | Values: 178.0 184.0 184.0 187.0 185.0 184.0 179.0 251.0 183.0 184.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#3] audio-fft Cycles:10 Average:78.20 Median:82.50 stddev:15.63 (18.9%) stddev-sans-first:16.52 15:10:54 INFO - PROCESS | 1950 | Values: 82.0 60.0 58.0 107.0 86.0 83.0 85.0 80.0 84.0 57.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:80.00 stddev:11.81 (14.8%) stddev-sans-first:12.00 15:10:54 INFO - PROCESS | 1950 | Values: 88.0 72.0 96.0 71.0 67.0 69.0 92.0 68.0 69.0 91.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.00 stddev:1.84 (2.1%) stddev-sans-first:1.87 15:10:54 INFO - PROCESS | 1950 | Values: 89.0 87.0 90.0 86.0 88.0 85.0 90.0 87.0 88.0 85.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#6] imaging-darkroom Cycles:10 Average:107.70 Median:96.00 stddev:21.01 (21.9%) stddev-sans-first:18.75 15:10:54 INFO - PROCESS | 1950 | Values: 140.0 150.0 118.0 96.0 96.0 95.0 96.0 96.0 96.0 94.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#7] imaging-desaturate Cycles:10 Average:82.90 Median:83.00 stddev:2.73 (3.3%) stddev-sans-first:2.80 15:10:54 INFO - PROCESS | 1950 | Values: 81.0 81.0 79.0 83.0 82.0 83.0 85.0 86.0 81.0 88.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:2.26 (4.1%) stddev-sans-first:2.06 15:10:54 INFO - PROCESS | 1950 | Values: 51.0 50.0 54.0 54.0 55.0 57.0 57.0 55.0 55.0 55.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.50 stddev:3.03 (4.2%) stddev-sans-first:2.47 15:10:54 INFO - PROCESS | 1950 | Values: 66.0 73.0 74.0 77.0 73.0 69.0 70.0 71.0 70.0 72.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:87.50 stddev:1.49 (1.7%) stddev-sans-first:1.58 15:10:54 INFO - PROCESS | 1950 | Values: 88.0 90.0 87.0 87.0 90.0 86.0 89.0 86.0 87.0 87.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#11] stanford-crypto-ccm Cycles:10 Average:155.40 Median:155.50 stddev:14.02 (9.0%) stddev-sans-first:14.66 15:10:54 INFO - PROCESS | 1950 | Values: 162.0 149.0 162.0 166.0 140.0 145.0 148.0 149.0 187.0 146.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:173.00 stddev:1.91 (1.1%) stddev-sans-first:1.99 15:10:54 INFO - PROCESS | 1950 | Values: 173.0 173.0 174.0 171.0 174.0 171.0 169.0 170.0 170.0 174.0 15:10:54 INFO - PROCESS | 1950 | 15:10:54 INFO - PROCESS | 1950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:75.00 stddev:9.92 (13.2%) stddev-sans-first:10.06 15:10:54 INFO - PROCESS | 1950 | Values: 68.0 71.0 77.0 92.0 89.0 89.0 73.0 69.0 67.0 68.0 15:10:54 INFO - PROCESS | 1950 | -------- Summary: end -------- 15:10:54 INFO - PROCESS | 1950 | 15:10:55 INFO - TEST-INFO | 1950: exit 0 15:10:55 INFO - TEST-OK | kraken | took 59741ms 15:10:55 INFO - SUITE-END | took 1179s 15:10:55 INFO - Completed test suite (00:19:39) 15:10:55 INFO - javascript benchmark 15:10:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.801711897286, 3317.568716066911, 3345.2807646356027, 3353.4131526982756, 3400.642932492088, 5551.833444996657, 5651.095824668048, 5670.943290567093, 5674.2623458950275, 5696.800611978581, 5629.296337957755, 5634.4928956393915, 5721.942780572193, 5751.539876809856, 5768.336641286253, 5059.671121377117, 5105.0, 5123.308353372303, 5182.222666600002, 5188.4811518848, 5256.5794736421185, 5268.209768534713, 5284.180951952453, 5296.417394086667, 5335.093034184179, 5117.360329958755, 5263.26314315996, 5264.15773476242, 5280.656757310781, 5332.840014799563, 5173.456787037382, 5182.818601348935, 5219.791208351661, 5239.978204250173, 5256.500632439912, 4285.678574106923, 5147.639665223436, 5197.194434862602, 5209.453007434203, 5251.737413129366, 5631.2397826293745, 5681.687507187103, 5715.942840571624, 5724.084146536534, 5755.68343741094, 6046.3349031606485, 6092.7258273377365, 6135.355787642323, 6137.079438084323, 6192.225971753531, 6218.657973811439, 6223.7821676241565, 6282.811515654537, 6291.056341548759, 6293.9370606293805, 5744.540436765049, 5744.597878148489, 5746.360008359803, 5747.770089196469, 5796.652200867961, 6208.968955155286, 6245.437910588069, 6270.122182894398, 6305.653189074553, 6324.936750632527, 5953.28560572734, 6072.271327440647, 6085.117657939574, 6087.269527656621, 6113.897846251784, 6222.564420490613, 6231.626102433778, 6243.625382476975, 6249.281332646713, 6252.5935814171935, 6108.847278818065, 6148.1085242639565, 6152.3540028297275, 6170.352113028157, 6175.0, 6006.159137720722, 6068.514518838482, 6070.119832624245, 6108.3280839107665, 6136.079588061826, 4813.542713442217, 4839.99999999993, 4868.561829435368, 4876.4879687633, 4882.975585122052, 4299.54854740254, 4340.60934515895, 4449.377087207794, 4458.108378324348, 4458.152950939311, 5654.604177707521, 5664.54683625309, 5667.801626943037, 5669.291338582677, 5671.319441667027, 6144.787876364505, 6149.262088549313, 6150.600210986271, 6160.829442405929, 6178.0, 5647.435256474319, 5671.9716401418555, 5673.574481913873, 5681.0910254359105, 5685.1756495307945, 5342.813001544927, 5343.171808369709, 5354.973225133849, 5368.570514358842, 5374.086405311028, 6286.717097730613, 6286.842828929313, 6290.370962903673, 6307.463865571386, 6322.589031712924, 6084.178635884099, 6116.694165291628, 6171.59884607499, 6266.874662506815, 6295.307516173309, 6234.9688251558455, 6288.151099601495, 6304.71628776697, 6318.210223722035, 6321.96839015802, 5714.7998920225555, 6154.753809847556, 6160.168377269011, 6174.25908890936, 6176.752929882754, 6112.755489780359, 6236.75052997875, 6240.594361366497, 6263.843403914939, 6266.122742816101, 4955.40535135786, 5968.045112781934, 6023.3073196582955, 6045.304789949212, 6110.7861224856915, 6023.879522409439, 6040.6677632730625, 6041.516678665608, 6047.455728984237, 6122.78570250057, 5818.33089194748, 5822.737976791139, 5852.151438041375, 5878.500327472042, 5896.439838215363, 4543.886402839956, 5844.620099693507, 5855.707214639337, 6050.485708714808, 6071.848203794941, 5802.332731735735, 5849.151872978309, 5855.2973643163095, 5914.290285165807, 5962.046072628359], "value": 5647.517018561667, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.2441660219133, 465.2021782642767, 478.26347424965553, 535.2559941681064, 535.908895487767, 29787.0, 38488.0, 39718.801405993, 39843.80078099609, 42028.78985605075, 40812.59187408125, 41110.0, 41135.58864411355, 41337.58662413375, 41356.793216033875, 9280.489573073479, 9453.243740500779, 9462.101100395486, 9473.478958657272, 9477.336586438953, 8598.79616853641, 8823.77940551485, 9472.81054378912, 9473.81052378952, 9481.0, 8126.78098285259, 9472.147506724394, 9483.81032379352, 9492.905070949271, 9515.762105947337, 9416.81166376672, 9466.857997130048, 9486.19367353769, 9498.19265362445, 9509.667161649344, 7931.206879312081, 7979.401544884099, 9255.305852061123, 9300.0, 9321.114494123047, 39407.21185576301, 39642.603573964465, 39675.0, 39702.60297397018, 39778.80110599457, 3258.96741032589, 3299.7525185611175, 3554.5117624908357, 3788.6211378862167, 3989.3617021276746, 29161.56257656137, 29525.40949181026, 29633.407331853243, 31005.379892402252, 31076.73694262115, 3139.2465808206093, 3176.1241702849775, 3243.1405677495245, 3254.495553189259, 3324.268660894599, 457517.0, 458461.0, 458490.0, 459330.70334648446, 469949.0, 54335.0, 54678.45321546813, 55032.724836375564, 55265.72367138219, 55332.72333638306, 58809.5297617563, 58816.70591647014, 58839.0, 58912.11631825526, 58925.41074589285, 15924.76112858308, 16125.596860078593, 16132.112733799519, 16141.515754527383, 16144.0, 12574.497020119094, 12667.93666031664, 12687.302198378993, 12688.6827829305, 12689.746205075848, 14631.487897923735, 14846.331915063847, 14913.0, 14926.701465970837, 15015.774763378558, 6805.727770889208, 6918.342757438136, 6954.40887524556, 6999.005283494282, 7044.471664625174, 911.4759013567225, 1019.1948360795037, 1037.4449669426972, 1056.9312994655322, 1072.1369297715257, 23647.172348967702, 28729.425411491655, 29265.56101658477, 30001.699983000326, 30145.246368840955, 962.8748262725941, 1070.2668671959732, 1087.1683162380784, 1120.675004248775, 1213.9210951288137, 12300.938495307466, 13269.601911942656, 15256.932014759082, 15403.845961540464, 15632.4528641497, 610574.0, 613681.0, 616607.0, 617336.0, 618288.0, 55586.16620750692, 55760.0, 55776.0, 55809.720951394986, 55959.160612590844, 1228.1893949993141, 1281.628327784932, 1314.1983390132011, 1369.6851023018014, 1416.1377788208883, 29255.707442925297, 30297.93957211487, 30753.077407677807, 30770.846145769126, 31093.378132436774, 46207.53792462032, 46493.0, 46620.83447913829, 46721.0, 46770.53229467662, 521155.0, 524886.0, 525222.0, 526404.0, 527050.0, 37119.814400927826, 37553.6244637561, 37832.05419864525, 38006.80996594999, 38034.61965380421, 45302.86742831456, 45327.0, 46030.0, 46327.0, 47009.0, 37328.0, 37485.4377184337, 37823.621763783114, 38578.421323680726, 38864.0, 37294.81352593219, 38024.696287570296, 38245.04387390338, 38623.42064869085, 39033.2193356137, 36944.445833311984, 37164.44253336148, 37789.0, 37905.0, 38053.0, 30450.88912996717, 33016.84441044552, 33560.83219583886, 33621.82323618662, 33672.663273366954, 24201.75798241995, 24388.02447902135, 24660.50678986446, 24978.250652480456, 25363.61954570646], "value": 19467.900009819932, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.4161804123969, 364.5497810204397, 365.63619198897095, 375.4143536083709, 396.5697218517908, 14950.77573836392, 15163.469278575252, 16318.673626527478, 20695.793042069574, 21147.682784758203, 20771.480712982182, 21264.0, 21840.126394944222, 22079.779202207974, 22267.44331391716, 5623.353314368838, 5625.831225063244, 5886.558508111887, 5917.082852157922, 5918.2898052233795, 5839.240898683186, 6013.939860601382, 6014.278286605591, 6018.307894592134, 6036.667983260919, 6001.87996240075, 6210.627362358273, 6573.276939536647, 6599.373059559334, 6682.5656332338485, 5962.731677074542, 6148.200733904609, 6550.377714117151, 6588.341165883421, 6609.140811694497, 5804.448577385142, 5808.1578171165365, 5810.041343178371, 5813.505852002542, 5813.505852002585, 12783.296918669563, 13023.804642930363, 13071.54249601269, 13112.27882466464, 13274.336283185801, 2144.699742036101, 2166.6425039868404, 2250.9549809003893, 2311.90752369905, 2804.621376114219, 7851.646675899598, 8827.823443531093, 9784.95107524465, 10344.434927691926, 10555.73610659732, 1826.3607737291975, 1840.7699037620298, 1870.5043163561668, 1876.9249230030782, 1891.186789680437, 112183.0, 118065.0, 118865.0, 119363.99999999913, 119606.0, 10058.145057670034, 11221.0, 11228.606998755004, 11341.0, 11611.303321800719, 13441.260730659907, 14516.854831451761, 14532.418703251751, 14534.345954432076, 14557.272136393138, 6891.0, 8248.13394593559, 8422.831543369099, 8568.828623427496, 8642.78393040179, 7002.159740831133, 8232.300254478316, 8300.916990830014, 8322.70870519529, 8520.616572254263, 4400.141972315368, 4472.843450479218, 4526.275795872644, 4526.502084770673, 4568.862934111916, 1827.8629102817076, 1885.698288273897, 1888.7450194223604, 1892.7823300320365, 1893.384649988759, 842.7850898020965, 864.3636907432307, 902.625410454654, 1007.0684616729496, 1016.9644062457778, 20344.18623255083, 21712.221003469334, 21827.0, 21831.31430385642, 21867.61405760993, 652.4975768658106, 853.5063638632394, 941.1012483078644, 959.8896126945351, 968.8062387522524, 10772.030517253485, 10922.071623912056, 11253.943730281297, 11467.0, 11471.254368466023, 561030.0, 580126.0, 583820.0, 595268.0, 599781.0, 121142.0, 122469.38765306116, 124258.0, 126178.36910815387, 135317.0, 976.8876579193342, 1003.3377970539555, 1046.1369370269558, 1047.2459828923163, 1048.7849990751877, 18110.0, 18520.81479185191, 18563.071846407896, 18630.627387452445, 19116.713249300992, 52299.47700522946, 52959.20561191508, 53910.73044634909, 54363.72818135884, 55067.0, 70774.0, 72961.15024471628, 72982.0, 73247.53504929978, 81147.0, 6446.000869865178, 6977.686004129725, 7986.401019923645, 8012.5192488450875, 8075.838483230185, 10428.791424171626, 12680.365981700694, 12778.74442511126, 13234.536791212262, 13262.867371326163, 10754.89245107539, 10804.351738895692, 11051.889481105087, 11060.225784195029, 11131.777364452828, 5002.499750024968, 7428.925710743039, 9339.486328252011, 11000.559977601126, 11115.777264500737, 9042.276617870422, 10958.342499450058, 11438.599649012245, 11607.941960290145, 11708.121890858049, 7932.620213935911, 7950.7217247396065, 7998.200179981955, 8024.197580242163, 8039.226466868921, 9911.256655750993, 10826.350418974887, 10988.285761425483, 11134.0, 11221.046211071824], "value": 9250.38411867362, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1201.2672269915356, 1535.9539213823582, 1571.787808645833, 1795.6318954614305, 1943.9708404373937, 7678.577678227695, 8132.634031468583, 8433.0, 8708.04211536732, 8717.86923196151, 8544.359173062014, 8647.740567782977, 8655.69705060323, 8659.0, 8660.696875609356, 2320.8607483550954, 2357.787799098081, 2358.351453350334, 2362.9291121266388, 2365.2431222008963, 2297.701298831158, 2335.4628435459854, 2344.2381226101584, 2347.7769611886843, 2351.071326825903, 1950.765908091031, 2302.286291249718, 2332.3236261484235, 2335.6963594732747, 2342.063174730113, 2292.083166733303, 2300.919467818627, 2313.687652166953, 2319.7100362454694, 2324.5002324500315, 2269.0923630547913, 2311.6070268054305, 2315.4211447138214, 2319.408550819547, 2327.51122264323, 8486.32109431244, 8627.741167764914, 8697.869531957025, 8699.652013919436, 8703.47779133254, 5157.613179011589, 5377.973110134424, 5390.110631745757, 5391.703456309901, 5404.729763511808, 3585.444256140276, 3586.982065089684, 3662.029562166028, 3667.394879844849, 3668.211334563082, 1037.3153718545723, 1043.4678314059809, 1050.5167622893478, 1054.6361505280665, 1068.439069488517, 10914.617988370368, 10964.835527467094, 11054.834177487344, 11057.170712196617, 11062.28095173803, 4207.537170911197, 4329.0259691569145, 4443.466783985942, 4500.729956202638, 4516.006478574708, 7423.740169094164, 8773.561321933877, 8773.692920747872, 8785.604647790864, 8794.164554367326, 5047.091523525801, 5049.444561098277, 5051.520105589963, 5052.469490703496, 5053.494650534991, 4180.159823911235, 4390.714603550759, 4469.9553004469535, 4566.566176213254, 4718.292256161603, 3543.8759643412873, 3858.459815625815, 3864.652181303696, 3868.0, 3870.148567315186, 1116.5812820192427, 1138.9487473063732, 1143.3654321851452, 1158.264502041193, 1159.3913195572222, 892.3084609427746, 921.4517362169498, 923.2752289452748, 925.6716611662287, 960.078324808192, 5730.455606717355, 5797.505816937107, 5998.0, 6007.789727359522, 6060.878782424327, 412.12012353625227, 427.8203154675044, 430.1847998043752, 432.10769760444583, 435.31655301176846, 3868.6711629511806, 3994.580569040267, 4004.6395824375945, 4019.618136277049, 4022.416749571296, 9851.014898510235, 10300.484975751182, 10598.735031624272, 10709.625163119254, 10741.462926853783, 10017.499125043718, 10388.37669739818, 10392.37645741258, 10498.947505262577, 10528.842067368996, 7570.848583028309, 7840.0, 7953.761387158279, 8000.479968802009, 8027.07688615805, 5582.581306402118, 5823.7379317931645, 5931.961906666403, 5999.940000599938, 6083.6045657032155, 9504.23966082698, 9887.752806179904, 9912.35569687968, 9938.552765125442, 9942.602295908082, 9122.5438728062, 9426.340156189, 9892.802143956937, 9993.0, 10009.949950250202, 7518.8496230074, 7782.844343113219, 7895.921040789748, 8014.83970320602, 8102.635381407968, 8063.314618257514, 8129.430939834155, 8154.306883914934, 8185.38609604294, 8202.097769245498, 7377.741779037708, 7420.554766713799, 7442.553446793209, 7489.0, 7500.0, 7187.676554554953, 7249.601271930094, 7273.745418910313, 7388.371988380833, 7395.963020184865, 6151.231096112986, 6718.025886246369, 6788.219354774066, 6850.075239842757, 6944.791656250321, 4872.610191184767, 4975.577075948585, 5006.0, 5030.0, 5168.302279192411, 4324.9135017300105, 5055.799123156607, 5062.0, 5071.290019397361, 5171.069207542679], "value": 4395.256668153816, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.66437589899317, 301.29496977073643, 308.87181819544884, 314.0750489807518, 316.77250653716845, 5003.824866129687, 5290.2593636890815, 5360.4639536046425, 5390.460953904608, 5416.945830541694, 3876.9806150969275, 4493.393391892094, 5005.324281222034, 5066.5693416059585, 5191.117510023296, 4031.5162180538377, 4033.1933613277315, 4068.2677118118727, 4073.5111786585503, 4086.652634526054, 3659.908502287451, 4043.3934909763475, 4049.2508885856064, 4073.287174744423, 4093.856715014975, 3270.394976929264, 3391.389549881033, 3718.814059297038, 4011.0, 4040.797960101998, 3891.0, 4006.959930400688, 4022.175454031931, 4044.3933409988445, 4070.618688067484, 2869.727375899307, 3449.724022078228, 3456.94814577779, 3457.792532448061, 3468.514407982884, 5005.299258103868, 5157.7936882524655, 5227.03986005248, 5243.816466423695, 5251.081060814342, 3849.538055433338, 4554.43069616298, 4578.862634120982, 4597.758580200951, 4602.286645569909, 4769.403824521934, 5090.0, 5553.666779993173, 5663.1505274208785, 5667.744951477193, 3770.245950809849, 3773.6037716039696, 3790.2608991246716, 3794.7912864792424, 3808.90477738056, 5339.038972984822, 5501.779928802843, 5644.830655080313, 5882.147088672162, 5890.734916928748, 3202.439573074703, 3242.9460425361854, 3578.874739384109, 3721.404575267944, 3835.0, 3420.8369154487596, 3508.4211105167874, 3533.4876442916147, 3745.9250814983548, 3813.7521061130938, 3890.455336252927, 3965.6827453803626, 4309.41822853917, 4578.427696537933, 4601.401817763669, 5631.605787594911, 5650.406707295757, 5698.202251684767, 5711.885762284731, 5727.656340619576, 1168.3690806964314, 1243.7313939781372, 1315.2963164706837, 1336.946522139123, 1356.816829727974, 739.7965559471188, 741.0, 832.2592892325835, 836.3189988123793, 856.1823458597025, 4125.731827431207, 4451.933221001687, 4534.841280555164, 4542.454905411372, 4545.0909818036525, 4595.379623750818, 4641.3734145889875, 4910.74775932135, 4929.408470983505, 4944.961558072773, 3356.0, 4119.835206591702, 4346.456692913386, 4554.954450455453, 4557.1087998480925, 2425.229582404854, 2520.508500842318, 2540.911068112607, 2553.7446255374316, 2554.5274124287066, 4432.889177770581, 4714.339992401066, 4803.951960480351, 4935.432425271068, 4969.35398398206, 4158.707199680826, 4232.407462955189, 4773.427188737373, 4837.274408838703, 4931.950680493221, 4572.193983376547, 4615.23848564983, 4897.3878265216845, 4898.191798353232, 4918.606511479073, 4288.506821715543, 4663.945115949059, 4735.313379559876, 4770.761461926821, 4772.3557319761385, 4507.57178068083, 4720.527947205252, 4810.374651295309, 5172.612054095883, 5652.0674088774895, 4501.572350626685, 5747.971260143672, 6062.42406971337, 6076.483498902643, 6111.480524155497, 3199.4560924643333, 3865.96134038656, 3874.18642085154, 3888.6111388860886, 3891.7470364427354, 4924.49802810132, 5388.622796404371, 5776.855578610568, 5800.419958004166, 5836.212111364912, 5629.718514074362, 5643.294588176478, 5649.079313033589, 5666.943330566642, 5682.318121825408, 5053.8881446081805, 5334.919976200283, 5612.803551875665, 5645.887082258249, 5686.43135686428, 5226.425093239664, 5321.866953326198, 5516.310461192351, 5563.415841336601, 5568.025595520849, 5389.466950533243, 5523.447655234444, 5524.254225679482, 5676.183621241164, 5696.974544582015, 4577.931331030104, 4898.403845961646, 5251.002309561171, 5280.3663560373, 5511.200875872896], "value": 3879.972498801107, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.2031204738645, 753.6194221917931, 806.8830019647147, 828.6022709099632, 842.6294820717128, 15298.69402611947, 15818.76271855923, 15869.76195357069, 15879.92060039701, 15887.364505419784, 14098.788518172236, 14582.635434114152, 14831.55505334839, 14843.851561484382, 14870.405183792634, 878.3631866896512, 901.1214066285352, 901.7249738829654, 902.4449963272573, 902.8284625921087, 701.3687681087011, 719.8920161975693, 730.9963450182742, 871.1462766488845, 894.8791913091746, 963.3786207895903, 968.5931908598405, 969.1277849935079, 970.1365784451843, 974.2449601558814, 924.7179610218847, 925.4539821505344, 941.9952900235523, 965.097633712474, 967.6709918627665, 538.1148011521026, 548.9121740521538, 675.0448115915973, 693.0989713372595, 709.5387997801419, 15263.923680381527, 15385.923070384688, 15391.615209619737, 15394.0, 15404.922975385163, 12448.937755311255, 13722.862771372258, 13913.443462261497, 14014.0, 14046.087004344787, 12469.0, 12569.183003104768, 12594.181378210478, 12642.241465512006, 13165.53920612774, 4403.207422663919, 4430.712003719748, 4483.417155769762, 4525.751083690396, 4566.657500687462, 116501.41749291199, 116632.0, 118217.0, 120046.3997680006, 121005.78994210034, 10785.730356741144, 10906.672799816019, 10911.83632245517, 10978.12175026012, 11414.828777568344, 22237.332880013626, 22299.0, 22312.107515699514, 22322.441938951655, 22333.330000100024, 6816.693248803716, 6934.4452443804375, 7833.960830195927, 7962.920370796333, 7975.1626079262005, 4325.0, 4363.694541382073, 4380.888090298107, 4452.398926144993, 4566.086782643484, 3402.6427225141006, 3516.7186625069935, 3522.5772907251294, 3523.9823800881345, 3529.770564913272, 633.3065293503606, 642.1683919324486, 653.8757636049136, 669.4276393683427, 671.6104659297596, 551.6331639459795, 576.1933293389205, 599.3976054065723, 612.7640858269598, 641.8170821315903, 7816.49192802466, 7822.843543129219, 8920.73237802867, 8960.115098713091, 8968.192862642394, 520.0, 546.9097598896217, 558.3634656491603, 571.1489880078653, 573.1746285349084, 4041.6160464755803, 4380.737155770664, 4394.274944634163, 4450.46594408673, 4515.616172625298, 164278.0, 164958.3504164967, 166067.0, 169220.1538992322, 173184.1340793288, 72556.63721681357, 75238.62380688061, 84938.0, 113264.0, 119572.40213798876, 83140.58429708055, 102074.48962755139, 105608.47195763972, 105748.99999999846, 106538.0, 9027.50348730826, 9321.30090243221, 9333.766655833659, 9715.659951901649, 9750.31747777677, 57672.134917977106, 57820.0, 58169.70915145397, 59181.81636367163, 60978.0, 59887.0, 82550.17449825424, 85767.5711621438, 87377.12622873943, 89093.0, 12680.23918564889, 12714.237145771283, 12737.163723079364, 13070.150440221356, 13125.868741312464, 14426.42294308258, 14524.273786310854, 14628.26858657084, 14647.853521465075, 14993.0, 12899.871001289866, 13202.603921882359, 13203.07578469498, 13462.0, 13951.720965580429, 13524.0, 13721.794173087212, 13801.0, 13805.240711760547, 13820.378082986088, 12965.740685186433, 13019.674508137372, 13200.537981170613, 13485.46058157702, 13866.722665546835, 9688.951555242178, 9921.40471571708, 11029.724256893322, 11460.598879039193, 11825.881741182478, 8325.917630707969, 10905.0, 11143.554257829921, 11295.774084518427, 11378.203525753117], "value": 7717.414284976234, "unit": "score"}], "name": "dromaeo_css", "value": 7152.287990979653, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 120.0, 163.0, 159.0, 113.0, 112.0, 111.0, 113.0, 112.0, 111.0], "value": 121.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 111.0, 115.0, 150.0, 112.0, 114.0, 113.0, 114.0, 145.0, 112.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 184.0, 184.0, 187.0, 185.0, 184.0, 179.0, 251.0, 183.0, 184.0], "value": 189.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 58.0, 107.0, 86.0, 83.0, 85.0, 80.0, 84.0, 57.0], "value": 78.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 96.0, 71.0, 67.0, 69.0, 92.0, 68.0, 69.0, 91.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 86.0, 88.0, 85.0, 90.0, 87.0, 88.0, 85.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 150.0, 118.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 94.0], "value": 107.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 79.0, 83.0, 82.0, 83.0, 85.0, 86.0, 81.0, 88.0], "value": 82.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 54.0, 54.0, 55.0, 57.0, 57.0, 55.0, 55.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 74.0, 77.0, 73.0, 69.0, 70.0, 71.0, 70.0, 72.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 87.0, 87.0, 90.0, 86.0, 89.0, 86.0, 87.0, 87.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 149.0, 162.0, 166.0, 140.0, 145.0, 148.0, 149.0, 187.0, 146.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 174.0, 171.0, 174.0, 171.0, 169.0, 170.0, 170.0, 174.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 77.0, 92.0, 89.0, 89.0, 73.0, 69.0, 67.0, 68.0], "value": 76.3, "unit": "score"}], "name": "kraken", "value": 1487.2, "alertThreshold": 2.0}]} 15:10:55 INFO - Return code: 0 15:10:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:10:55 INFO - # TBPL SUCCESS # 15:10:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:10:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:10:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:10:55 INFO - 2016-10-14 15:10:55.534 screenresolution[1954:14618] starting screenresolution argv=screenresolution get 15:10:55 INFO - 2016-10-14 15:10:55.539 screenresolution[1954:14618] Display 0: 1600x1200x32@60 15:10:55 INFO - 2016-10-14 15:10:55.545 screenresolution[1955:14621] starting screenresolution argv=screenresolution list 15:10:55 INFO - Available Modes on Display 0 15:10:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:10:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:10:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:10:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:10:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:10:55 INFO - Intel Iris: 15:10:55 INFO - Chipset Model: Intel Iris 15:10:55 INFO - Type: GPU 15:10:55 INFO - Bus: Built-In 15:10:55 INFO - VRAM (Dynamic, Max): 1536 MB 15:10:55 INFO - Vendor: Intel (0x8086) 15:10:55 INFO - Device ID: 0x0a2e 15:10:55 INFO - Revision ID: 0x0009 15:10:55 INFO - Displays: 15:10:55 INFO - Display: 15:10:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:10:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:10:55 INFO - Display Serial Number: 1600x1200 60 15:10:55 INFO - Main Display: Yes 15:10:55 INFO - Mirror: Off 15:10:55 INFO - Online: Yes 15:10:55 INFO - Rotation: Supported 15:10:55 INFO - Return code: 0 15:10:55 INFO - Running post-action listener: _resource_record_post_action 15:10:55 INFO - [mozharness: 2016-10-14 22:10:55.790932Z] Finished run-tests step (success) 15:10:55 INFO - Running post-run listener: _resource_record_post_run 15:10:55 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 798720; Write bytes: 709195776; Read time: 13; Write time: 2064 15:10:55 INFO - TinderboxPrint: CPU usage
53.5% 15:10:55 INFO - TinderboxPrint: I/O read bytes / time
798,720 / 13 15:10:55 INFO - TinderboxPrint: I/O write bytes / time
709,195,776 / 2,064 15:10:55 INFO - TinderboxPrint: CPU idle
2,260.8 (46.5%) 15:10:55 INFO - TinderboxPrint: CPU system
975.0 (20.1%) 15:10:55 INFO - TinderboxPrint: CPU user
1,625.9 (33.4%) 15:10:55 INFO - TinderboxPrint: Swap in / out
408,723,456 / 0 15:10:55 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 408801792; Write bytes: 411973632; Read time: 31396; Write time: 768 15:10:55 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 716800; Write bytes: 295890944; Read time: 10; Write time: 1290 15:10:56 INFO - Running post-run listener: _upload_blobber_files 15:10:56 INFO - Blob upload gear active. 15:10:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:10:56 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 15:10:56 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'] 15:10:56 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 15:10:56 INFO - (blobuploader) - INFO - Open directory for files ... 15:10:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:10:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:10:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:10:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:10:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:10:58 INFO - (blobuploader) - INFO - Done attempting. 15:10:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:10:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:10:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:11:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:11:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:11:00 INFO - (blobuploader) - INFO - Done attempting. 15:11:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:11:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:11:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:11:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:11:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:11:03 INFO - (blobuploader) - INFO - Done attempting. 15:11:03 INFO - (blobuploader) - INFO - Iteration through files over. 15:11:03 INFO - Return code: 0 15:11:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:11:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:11:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/331f6f639e149330a39365ba1ea43ad9b3cae3e15b53dd77106aa9123c88b1f15bb866769c2c122ca2b85d91b751fab64a2213bf7448241e84ff7e979d0c3f17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86dfda040a0bc9f2b06496c63168618fb8be4c39370a1e5f5a2cd14fe34569f3c57a6dd84ef041c34ed0970007dd833e782a76c94b7d5421ec866fc88a9a734f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b77d03419373c2be40c3697173dab48c07dae01941ab2c0ec06ee64dfdab73d2cea86701f3340c6594edde545d14e3a2a7c8457ff0a1e633fa787fa66a646fed"} 15:11:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:11:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:11:03 INFO - Contents: 15:11:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/331f6f639e149330a39365ba1ea43ad9b3cae3e15b53dd77106aa9123c88b1f15bb866769c2c122ca2b85d91b751fab64a2213bf7448241e84ff7e979d0c3f17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86dfda040a0bc9f2b06496c63168618fb8be4c39370a1e5f5a2cd14fe34569f3c57a6dd84ef041c34ed0970007dd833e782a76c94b7d5421ec866fc88a9a734f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b77d03419373c2be40c3697173dab48c07dae01941ab2c0ec06ee64dfdab73d2cea86701f3340c6594edde545d14e3a2a7c8457ff0a1e633fa787fa66a646fed"} 15:11:03 INFO - Running post-run listener: copy_logs_to_upload_dir 15:11:03 INFO - Copying logs to upload dir... 15:11:03 INFO - mkdir: /builds/slave/test/build/upload/logs 15:11:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.848327 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-14 15:11:03.104668) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:03.108301) ========= 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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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/331f6f639e149330a39365ba1ea43ad9b3cae3e15b53dd77106aa9123c88b1f15bb866769c2c122ca2b85d91b751fab64a2213bf7448241e84ff7e979d0c3f17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86dfda040a0bc9f2b06496c63168618fb8be4c39370a1e5f5a2cd14fe34569f3c57a6dd84ef041c34ed0970007dd833e782a76c94b7d5421ec866fc88a9a734f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b77d03419373c2be40c3697173dab48c07dae01941ab2c0ec06ee64dfdab73d2cea86701f3340c6594edde545d14e3a2a7c8457ff0a1e633fa787fa66a646fed"} build_url:https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011356 build_url: 'https://queue.taskcluster.net/v1/task/JL3eTwXrRSKtviJ2Qf7tMQ/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/331f6f639e149330a39365ba1ea43ad9b3cae3e15b53dd77106aa9123c88b1f15bb866769c2c122ca2b85d91b751fab64a2213bf7448241e84ff7e979d0c3f17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86dfda040a0bc9f2b06496c63168618fb8be4c39370a1e5f5a2cd14fe34569f3c57a6dd84ef041c34ed0970007dd833e782a76c94b7d5421ec866fc88a9a734f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b77d03419373c2be40c3697173dab48c07dae01941ab2c0ec06ee64dfdab73d2cea86701f3340c6594edde545d14e3a2a7c8457ff0a1e633fa787fa66a646fed"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:03.140794) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:03.141121) ========= 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.IFHOfYBjy5/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.sRcwcNWX5I/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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.005580 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 15:11:03.189781) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 15:11:03.190096) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 15:11:03.190401) ========= ========= Total master_lag: 0.18 =========