builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0172 starttime: 1476320293.09 results: success (0) buildid: 20161012144547 builduid: bfe8229c097446c2a44a972f6a05e5bd revision: d68def1644c1849141ceaa31bc1b811becfbb8da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:13.091469) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:13.091905) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:13.092176) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:13.133983) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:13.134245) ========= 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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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-12 17:58:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 223M=0s 2016-10-12 17:58:13 (223 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155007 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:13.311280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:13.311582) ========= 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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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.028563 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:13.359957) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 17:58:13.360274) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d68def1644c1849141ceaa31bc1b811becfbb8da --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d68def1644c1849141ceaa31bc1b811becfbb8da --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ahjY9j2vwh/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.qKeFBMiHVb/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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-12 17:58:13,435 truncating revision to first 12 chars 2016-10-12 17:58:13,435 Setting DEBUG logging. 2016-10-12 17:58:13,436 attempt 1/10 2016-10-12 17:58:13,436 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d68def1644c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 17:58:14,689 unpacking tar archive at: fx-team-d68def1644c1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.509222 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 17:58:14.891953) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:14.892323) ========= script_repo_revision: d68def1644c1849141ceaa31bc1b811becfbb8da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:14.892878) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:14.893322) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 17:58:14.910863) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-12 17:58:14.911162) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ahjY9j2vwh/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.qKeFBMiHVb/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:58:15 INFO - MultiFileLogger online at 20161012 17:58:15 in /builds/slave/test 17:58:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:58:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:58:15 INFO - {'append_to_log': False, 17:58:15 INFO - 'base_work_dir': '/builds/slave/test', 17:58:15 INFO - 'blob_upload_branch': 'Fx-Team', 17:58:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:58:15 INFO - 'branch': 'Fx-Team', 17:58:15 INFO - 'buildbot_json_path': 'buildprops.json', 17:58:15 INFO - 'config_files': ('talos/mac_config.py',), 17:58:15 INFO - 'default_actions': ('clobber', 17:58:15 INFO - 'read-buildbot-config', 17:58:15 INFO - 'download-and-extract', 17:58:15 INFO - 'populate-webroot', 17:58:15 INFO - 'create-virtualenv', 17:58:15 INFO - 'install', 17:58:15 INFO - 'run-tests'), 17:58:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:58:15 INFO - 'download_minidump_stackwalk': True, 17:58:15 INFO - 'download_symbols': 'ondemand', 17:58:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:58:15 INFO - 'tooltool.py': '/tools/tooltool.py', 17:58:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:58:15 INFO - '/tools/misc-python/virtualenv.py')}, 17:58:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:58:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:58:15 INFO - 'installer_path': 'installer.exe', 17:58:15 INFO - 'log_level': 'info', 17:58:15 INFO - 'log_name': 'talos', 17:58:15 INFO - 'log_to_console': True, 17:58:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:58:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:58:15 INFO - 'opt_config_files': (), 17:58:15 INFO - 'pip_index': False, 17:58:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:58:15 INFO - 'cmd': ('bash', 17:58:15 INFO - '-c', 17:58:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:58:15 INFO - 'enabled': True, 17:58:15 INFO - 'halt_on_failure': False, 17:58:15 INFO - 'name': 'check_screen_resolution'},), 17:58:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:58:15 INFO - 'cmd': ('bash', 17:58:15 INFO - '-c', 17:58:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:58:15 INFO - 'enabled': True, 17:58:15 INFO - 'halt_on_failure': False, 17:58:15 INFO - 'name': 'check_screen_resolution'},), 17:58:15 INFO - 'run_cmd_checks_enabled': True, 17:58:15 INFO - 'sps_profile': False, 17:58:15 INFO - 'sps_profile_interval': 0, 17:58:15 INFO - 'suite': 'dromaeojs-e10s', 17:58:15 INFO - 'system_bits': '32', 17:58:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:58:15 INFO - 'title': 't-yosemite-r7-0172', 17:58:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:58:15 INFO - 'use_talos_json': True, 17:58:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:58:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:58:15 INFO - 'work_dir': 'build'} 17:58:15 INFO - [mozharness: 2016-10-13 00:58:15.133778Z] Running clobber step. 17:58:15 INFO - Running pre-action listener: _resource_record_pre_action 17:58:15 INFO - Running main action method: clobber 17:58:15 INFO - rmtree: /builds/slave/test/build 17:58:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:58:17 INFO - Running post-action listener: _resource_record_post_action 17:58:17 INFO - [mozharness: 2016-10-13 00:58:17.249403Z] Finished clobber step (success) 17:58:17 INFO - [mozharness: 2016-10-13 00:58:17.249529Z] Running read-buildbot-config step. 17:58:17 INFO - Running pre-action listener: _resource_record_pre_action 17:58:17 INFO - Running main action method: read_buildbot_config 17:58:17 INFO - Using buildbot properties: 17:58:17 INFO - { 17:58:17 INFO - "project": "", 17:58:17 INFO - "product": "firefox", 17:58:17 INFO - "script_repo_revision": "production", 17:58:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 17:58:17 INFO - "repository": "", 17:58:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 17:58:17 INFO - "buildid": "20161012144547", 17:58:17 INFO - "pgo_build": "False", 17:58:17 INFO - "basedir": "/builds/slave/test", 17:58:17 INFO - "buildnumber": 1, 17:58:17 INFO - "slavename": "t-yosemite-r7-0172", 17:58:17 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:58:17 INFO - "platform": "yosemite_r7", 17:58:17 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 17:58:17 INFO - "branch": "fx-team", 17:58:17 INFO - "repo_path": "integration/fx-team", 17:58:17 INFO - "revision": "d68def1644c1849141ceaa31bc1b811becfbb8da", 17:58:17 INFO - "stage_platform": "macosx64", 17:58:17 INFO - "builduid": "bfe8229c097446c2a44a972f6a05e5bd", 17:58:17 INFO - "slavebuilddir": "test" 17:58:17 INFO - } 17:58:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 17:58:17 INFO - Running post-action listener: _resource_record_post_action 17:58:17 INFO - [mozharness: 2016-10-13 00:58:17.252872Z] Finished read-buildbot-config step (success) 17:58:17 INFO - [mozharness: 2016-10-13 00:58:17.252989Z] Running download-and-extract step. 17:58:17 INFO - Running pre-action listener: _resource_record_pre_action 17:58:17 INFO - Running main action method: download_and_extract 17:58:17 INFO - mkdir: /builds/slave/test/build/tests 17:58:17 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')]} 17:58:17 INFO - trying https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 17:58:17 INFO - Downloading https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/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 17:58:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/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 17:58:18 INFO - Downloaded 1199 bytes. 17:58:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:58:18 INFO - Using the following test package requirements: 17:58:18 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:58:18 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:58:18 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 17:58:18 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:58:18 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 17:58:18 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:58:18 INFO - u'jsshell-mac.zip'], 17:58:18 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:58:18 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 17:58:18 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:58:18 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:58:18 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 17:58:18 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:58:18 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 17:58:18 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:58:18 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 17:58:18 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:58:18 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 17:58:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:58:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:58:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:58:18 INFO - Fetch https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:58:19 INFO - Http code: 200 17:58:19 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 17:58:19 INFO - x-amz-cf-id: S3h_ur6T-CQ_3WeCGq_HCXyXRpQntGHpqnX49HNa_BZdg_MZGrWbgg== 17:58:19 INFO - x-amz-version-id: 8H1Yo9A4aP6YKnA3Zz6FLWn6YFQdVnDp 17:58:19 INFO - Expected file size: 16511123 17:58:19 INFO - Obtained file size: 16511123 17:58:26 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 17:58:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:58:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:58:26 INFO - Fetch https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:58:27 INFO - Http code: 200 17:58:27 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 17:58:27 INFO - x-amz-cf-id: vTCdbb6xRpDNoed3B3mLznPOBa_QQq35doWcV-gSjEdc8TbeQDd9VQ== 17:58:27 INFO - x-amz-version-id: 8H1Yo9A4aP6YKnA3Zz6FLWn6YFQdVnDp 17:58:27 INFO - Expected file size: 16511123 17:58:27 INFO - Obtained file size: 16511123 17:58:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 17:58:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:58:33 INFO - Fetch https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 17:58:34 INFO - Http code: 200 17:58:34 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 17:58:34 INFO - x-amz-cf-id: FA2as1ZQFWPctYvlSBSaa1JPCzKcuXW4maz_5u-Uy4MZp_gw_WEXoQ== 17:58:34 INFO - x-amz-version-id: UCJ8bGSsXzvAnwbq.ZZdq3fva50xxV0C 17:58:34 INFO - Expected file size: 14409006 17:58:34 INFO - Obtained file size: 14409006 17:58:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:58:35 INFO - trying https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:58:35 INFO - Downloading https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 17:58:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/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 17:58:38 INFO - Downloaded 111694480 bytes. 17:58:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:58:38 INFO - mkdir: /builds/slave/test/properties 17:58:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:58:38 INFO - Writing to file /builds/slave/test/properties/build_url 17:58:38 INFO - Contents: 17:58:38 INFO - build_url:https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:58:39 INFO - Running post-action listener: _resource_record_post_action 17:58:39 INFO - Running post-action listener: set_extra_try_arguments 17:58:39 INFO - [mozharness: 2016-10-13 00:58:39.101422Z] Finished download-and-extract step (success) 17:58:39 INFO - [mozharness: 2016-10-13 00:58:39.101542Z] Running populate-webroot step. 17:58:39 INFO - Running pre-action listener: _resource_record_pre_action 17:58:39 INFO - Running main action method: populate_webroot 17:58:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:58:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:58:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:58:39 INFO - '--tppagecycles', 17:58:39 INFO - '7'], 17:58:39 INFO - 'tests': ['tsvgm']}}, 17:58:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:58:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:58:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:58:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:58:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:58:39 INFO - 'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:58:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:58:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:58:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:58:39 INFO - 'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['damp', 'tps']}, 17:58:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:58:39 INFO - 'tests': ['damp', 'tps']}, 17:58:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['dromaeo_dom']}, 17:58:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:58:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['basic_compositor_video']}, 17:58:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:58:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['a11yr', 17:58:39 INFO - 'ts_paint', 17:58:39 INFO - 'tpaint', 17:58:39 INFO - 'sessionrestore', 17:58:39 INFO - 'sessionrestore_no_auto_restore', 17:58:39 INFO - 'tabpaint']}, 17:58:39 INFO - 'other-e10s': {'tests': ['a11yr', 17:58:39 INFO - 'ts_paint', 17:58:39 INFO - 'tpaint', 17:58:39 INFO - 'sessionrestore', 17:58:39 INFO - 'sessionrestore_no_auto_restore', 17:58:39 INFO - 'tabpaint']}, 17:58:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:58:39 INFO - 'ts_paint', 17:58:39 INFO - 'tpaint', 17:58:39 INFO - 'sessionrestore', 17:58:39 INFO - 'sessionrestore_no_auto_restore', 17:58:39 INFO - 'tabpaint']}, 17:58:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:58:39 INFO - 'ts_paint', 17:58:39 INFO - 'tpaint', 17:58:39 INFO - 'sessionrestore', 17:58:39 INFO - 'sessionrestore_no_auto_restore', 17:58:39 INFO - 'tabpaint']}, 17:58:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['a11yr', 17:58:39 INFO - 'ts_paint', 17:58:39 INFO - 'tpaint', 17:58:39 INFO - 'sessionrestore', 17:58:39 INFO - 'sessionrestore_no_auto_restore', 17:58:39 INFO - 'tabpaint']}, 17:58:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['a11yr', 17:58:39 INFO - 'ts_paint', 17:58:39 INFO - 'tpaint', 17:58:39 INFO - 'sessionrestore', 17:58:39 INFO - 'sessionrestore_no_auto_restore', 17:58:39 INFO - 'tabpaint']}, 17:58:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['tsvgx', 17:58:39 INFO - 'tsvgr_opacity', 17:58:39 INFO - 'tart', 17:58:39 INFO - 'tscrollx', 17:58:39 INFO - 'cart']}, 17:58:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:58:39 INFO - 'tsvgr_opacity', 17:58:39 INFO - 'tart', 17:58:39 INFO - 'tscrollx', 17:58:39 INFO - 'cart']}, 17:58:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:58:39 INFO - 'talos_options': ['--disable-e10s'], 17:58:39 INFO - 'tests': ['tp5o']}, 17:58:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:58:39 INFO - 'tests': ['tp5o']}, 17:58:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:58:39 INFO - 'talos_options': ['--disable-e10s', 17:58:39 INFO - '--xperf_path', 17:58:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:58:39 INFO - 'tests': ['tp5n']}, 17:58:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:58:39 INFO - 'talos_options': ['--xperf_path', 17:58:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:58:39 INFO - 'tests': ['tp5n']}}, 17:58:39 INFO - 'talos.zip': {'path': '', 17:58:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:58:39 INFO - Running post-action listener: _resource_record_post_action 17:58:39 INFO - [mozharness: 2016-10-13 00:58:39.120259Z] Finished populate-webroot step (success) 17:58:39 INFO - [mozharness: 2016-10-13 00:58:39.120390Z] Running create-virtualenv step. 17:58:39 INFO - Running pre-action listener: _resource_record_pre_action 17:58:39 INFO - Running main action method: create_virtualenv 17:58:39 INFO - Creating virtualenv /builds/slave/test/build/venv 17:58:39 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 17:58:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:58:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:58:39 INFO - Using real prefix '/tools/python27' 17:58:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:58:39 INFO - Installing distribute.............................................................................................................................................................................................done. 17:58:42 INFO - Installing pip.................done. 17:58:42 INFO - Return code: 0 17:58:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:58:43 INFO - Reading from file tmpfile_stdout 17:58:43 INFO - Output received: 17:58:43 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) 17:58:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:58:43 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')]} 17:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:43 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')]} 17:58:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:43 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 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:58:43 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 17:58:43 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 17:58:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:58:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:43 INFO - 'HOME': '/Users/cltbld', 17:58:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:43 INFO - 'LOGNAME': 'cltbld', 17:58:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:43 INFO - 'MOZ_NO_REMOTE': '1', 17:58:43 INFO - 'NO_EM_RESTART': '1', 17:58:43 INFO - 'PAGER': '/bin/cat', 17:58:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:43 INFO - 'PWD': '/builds/slave/test', 17:58:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:43 INFO - 'SHELL': '/bin/bash', 17:58:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:58:43 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:58:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:43 INFO - 'USER': 'cltbld', 17:58:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:43 INFO - 'XPC_FLAGS': '0x0', 17:58:43 INFO - 'XPC_SERVICE_NAME': '0', 17:58:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:43 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 17:58:43 INFO - Cleaning up... 17:58:43 INFO - Return code: 0 17:58:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:58:43 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')]} 17:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:43 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')]} 17:58:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:43 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 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:58:43 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 17:58:43 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 17:58:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:58:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:43 INFO - 'HOME': '/Users/cltbld', 17:58:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:43 INFO - 'LOGNAME': 'cltbld', 17:58:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:43 INFO - 'MOZ_NO_REMOTE': '1', 17:58:43 INFO - 'NO_EM_RESTART': '1', 17:58:43 INFO - 'PAGER': '/bin/cat', 17:58:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:43 INFO - 'PWD': '/builds/slave/test', 17:58:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:43 INFO - 'SHELL': '/bin/bash', 17:58:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:58:43 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:58:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:43 INFO - 'USER': 'cltbld', 17:58:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:43 INFO - 'XPC_FLAGS': '0x0', 17:58:43 INFO - 'XPC_SERVICE_NAME': '0', 17:58:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:43 INFO - Downloading/unpacking psutil>=3.1.1 17:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:58:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:58:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:58:47 INFO - Installing collected packages: psutil 17:58:47 INFO - Running setup.py install for psutil 17:58:47 INFO - building 'psutil._psutil_osx' extension 17:58:47 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 17:58:48 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 17:58:48 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 17:58:48 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 17:58:48 INFO - building 'psutil._psutil_posix' extension 17:58:48 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 17:58:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:58:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:58:48 INFO - ^ 17:58:48 INFO - 1 warning generated. 17:58:48 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 17:58:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:58:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:58:48 INFO - Successfully installed psutil 17:58:48 INFO - Cleaning up... 17:58:48 INFO - Return code: 0 17:58:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:58:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:58:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:58:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:58:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:58:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:48 INFO - 'HOME': '/Users/cltbld', 17:58:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:48 INFO - 'LOGNAME': 'cltbld', 17:58:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:48 INFO - 'MOZ_NO_REMOTE': '1', 17:58:48 INFO - 'NO_EM_RESTART': '1', 17:58:48 INFO - 'PAGER': '/bin/cat', 17:58:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:48 INFO - 'PWD': '/builds/slave/test', 17:58:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:48 INFO - 'SHELL': '/bin/bash', 17:58:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:58:48 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:58:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:48 INFO - 'USER': 'cltbld', 17:58:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:48 INFO - 'XPC_FLAGS': '0x0', 17:58:48 INFO - 'XPC_SERVICE_NAME': '0', 17:58:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:58:49 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 17:58:49 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 17:58:49 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 17:58:49 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 17:58:49 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 17:58:49 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 17:58:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:58:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:58:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:58:51 INFO - Installing collected packages: mozsystemmonitor 17:58:51 INFO - Running setup.py install for mozsystemmonitor 17:58:51 INFO - Successfully installed mozsystemmonitor 17:58:51 INFO - Cleaning up... 17:58:51 INFO - Return code: 0 17:58:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:58:51 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')]} 17:58:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:51 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')]} 17:58:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:51 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 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:58:51 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 17:58:51 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 17:58:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:58:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:51 INFO - 'HOME': '/Users/cltbld', 17:58:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:51 INFO - 'LOGNAME': 'cltbld', 17:58:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:51 INFO - 'MOZ_NO_REMOTE': '1', 17:58:51 INFO - 'NO_EM_RESTART': '1', 17:58:51 INFO - 'PAGER': '/bin/cat', 17:58:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:51 INFO - 'PWD': '/builds/slave/test', 17:58:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:51 INFO - 'SHELL': '/bin/bash', 17:58:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:58:51 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:58:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:51 INFO - 'USER': 'cltbld', 17:58:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:51 INFO - 'XPC_FLAGS': '0x0', 17:58:51 INFO - 'XPC_SERVICE_NAME': '0', 17:58:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:52 INFO - Downloading/unpacking jsonschema==2.5.1 17:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:58:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:58:54 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 17:58:54 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 17:58:54 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 17:58:54 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 17:58:54 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 17:58:54 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 17:58:54 INFO - Downloading functools32-3.2.3-2.tar.gz 17:58:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:58:55 INFO - warning: no files found matching '*.txt' 17:58:55 INFO - no previously-included directories found matching 'build' 17:58:55 INFO - no previously-included directories found matching 'dist' 17:58:55 INFO - no previously-included directories found matching '.git*' 17:58:55 INFO - Installing collected packages: jsonschema, functools32 17:58:55 INFO - Running setup.py install for functools32 17:58:55 INFO - warning: no files found matching '*.txt' 17:58:55 INFO - no previously-included directories found matching 'build' 17:58:55 INFO - no previously-included directories found matching 'dist' 17:58:55 INFO - no previously-included directories found matching '.git*' 17:58:55 INFO - Successfully installed jsonschema functools32 17:58:55 INFO - Cleaning up... 17:58:55 INFO - Return code: 0 17:58:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:58:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:58:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:58:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:55 INFO - 'HOME': '/Users/cltbld', 17:58:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:55 INFO - 'LOGNAME': 'cltbld', 17:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:55 INFO - 'MOZ_NO_REMOTE': '1', 17:58:55 INFO - 'NO_EM_RESTART': '1', 17:58:55 INFO - 'PAGER': '/bin/cat', 17:58:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:55 INFO - 'PWD': '/builds/slave/test', 17:58:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:55 INFO - 'SHELL': '/bin/bash', 17:58:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:58:55 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:58:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:55 INFO - 'USER': 'cltbld', 17:58:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:55 INFO - 'XPC_FLAGS': '0x0', 17:58:55 INFO - 'XPC_SERVICE_NAME': '0', 17:58:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:58:55 INFO - Cleaning up... 17:58:55 INFO - Return code: 0 17:58:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:58:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:58:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:58:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:55 INFO - 'HOME': '/Users/cltbld', 17:58:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:55 INFO - 'LOGNAME': 'cltbld', 17:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:55 INFO - 'MOZ_NO_REMOTE': '1', 17:58:55 INFO - 'NO_EM_RESTART': '1', 17:58:55 INFO - 'PAGER': '/bin/cat', 17:58:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:55 INFO - 'PWD': '/builds/slave/test', 17:58:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:55 INFO - 'SHELL': '/bin/bash', 17:58:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:58:55 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:58:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:55 INFO - 'USER': 'cltbld', 17:58:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:55 INFO - 'XPC_FLAGS': '0x0', 17:58:55 INFO - 'XPC_SERVICE_NAME': '0', 17:58:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:55 INFO - Downloading/unpacking blobuploader==1.2.4 17:58:55 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 17:58:55 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 17:58:55 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 17:58:55 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 17:58:55 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 17:58:55 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 17:58:58 INFO - Downloading blobuploader-1.2.4.tar.gz 17:58:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:58:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:58:58 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 17:58:58 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 17:58:58 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 17:58:58 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 17:58:58 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 17:58:58 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 17:58:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:58:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:58:59 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 17:58:59 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 17:58:59 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 17:58:59 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 17:58:59 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 17:58:59 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 17:58:59 INFO - Downloading docopt-0.6.1.tar.gz 17:58:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:58:59 INFO - Installing collected packages: blobuploader, requests, docopt 17:58:59 INFO - Running setup.py install for blobuploader 17:58:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:58:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:58:59 INFO - Running setup.py install for requests 17:59:00 INFO - Running setup.py install for docopt 17:59:00 INFO - Successfully installed blobuploader requests docopt 17:59:00 INFO - Cleaning up... 17:59:00 INFO - Return code: 0 17:59:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:59:00 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')]} 17:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:00 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')]} 17:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:00 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 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:59:00 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 17:59:00 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 17:59:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:59:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:00 INFO - 'HOME': '/Users/cltbld', 17:59:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:00 INFO - 'LOGNAME': 'cltbld', 17:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:00 INFO - 'MOZ_NO_REMOTE': '1', 17:59:00 INFO - 'NO_EM_RESTART': '1', 17:59:00 INFO - 'PAGER': '/bin/cat', 17:59:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:00 INFO - 'PWD': '/builds/slave/test', 17:59:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:00 INFO - 'SHELL': '/bin/bash', 17:59:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:59:00 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:59:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:00 INFO - 'USER': 'cltbld', 17:59:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:00 INFO - 'XPC_FLAGS': '0x0', 17:59:00 INFO - 'XPC_SERVICE_NAME': '0', 17:59:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:59:00 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-qh6vYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-nmH5Yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-5lbgIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-e08lit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-81otEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-R5oG5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-veJIjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Ejt39e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-oiauH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-pkTNhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:59:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:59:01 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-r0DpXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:59:02 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-TfZNQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:59:02 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-0CpjHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:59:02 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Jxu2_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:59:02 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-DTXYqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:59:02 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-V5stMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:59:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:59:02 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-UrGmHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:59:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:59:02 INFO - Running setup.py install for manifestparser 17:59:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:59:02 INFO - Running setup.py install for mozcrash 17:59:02 INFO - Running setup.py install for mozdebug 17:59:03 INFO - Running setup.py install for mozdevice 17:59:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:59:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:59:03 INFO - Running setup.py install for mozfile 17:59:03 INFO - Running setup.py install for mozhttpd 17:59:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:59:03 INFO - Running setup.py install for mozinfo 17:59:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:59:03 INFO - Running setup.py install for mozInstall 17:59:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:59:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:59:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:59:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:59:03 INFO - Running setup.py install for mozleak 17:59:03 INFO - Running setup.py install for mozlog 17:59:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:59:04 INFO - Running setup.py install for moznetwork 17:59:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:59:04 INFO - Running setup.py install for mozprocess 17:59:04 INFO - Running setup.py install for mozprofile 17:59:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:59:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:59:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:59:04 INFO - Running setup.py install for mozrunner 17:59:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:59:04 INFO - Running setup.py install for mozscreenshot 17:59:04 INFO - Running setup.py install for moztest 17:59:05 INFO - Running setup.py install for mozversion 17:59:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:59:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:59:05 INFO - Cleaning up... 17:59:05 INFO - Return code: 0 17:59:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:59:05 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')]} 17:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:05 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')]} 17:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:05 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 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:59:05 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 17:59:05 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 17:59:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:59:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:05 INFO - 'HOME': '/Users/cltbld', 17:59:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:05 INFO - 'LOGNAME': 'cltbld', 17:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:05 INFO - 'MOZ_NO_REMOTE': '1', 17:59:05 INFO - 'NO_EM_RESTART': '1', 17:59:05 INFO - 'PAGER': '/bin/cat', 17:59:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:05 INFO - 'PWD': '/builds/slave/test', 17:59:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:05 INFO - 'SHELL': '/bin/bash', 17:59:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:59:05 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:59:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:05 INFO - 'USER': 'cltbld', 17:59:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:05 INFO - 'XPC_FLAGS': '0x0', 17:59:05 INFO - 'XPC_SERVICE_NAME': '0', 17:59:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:59:05 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-L5VAf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:59:05 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)) 17:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:59:05 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-wSkAJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:59:05 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)) 17:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:59:05 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Ib0MSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:59:05 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)) 17:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:59:05 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-naHqkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:59:05 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)) 17:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:59:05 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-JLkNkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:59:06 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)) 17:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:59:06 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-9xaen0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:59:06 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)) 17:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:59:06 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-TtO2zV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:59:06 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)) 17:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:59:06 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-YYkDYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:59:06 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)) 17:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:59:06 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-cR7mTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:59:06 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)) 17:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:59:06 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-PeVE0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:59:06 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)) 17:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:59:06 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-DFvsfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:59:06 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)) 17:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:59:06 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-ZSXPyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:59:06 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)) 17:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:59:06 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-oLx3GJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:59:06 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)) 17:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:59:06 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-D_zM_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:59:07 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)) 17:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:59:07 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-R3Ee4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:59:07 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)) 17:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:59:07 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-pBEMql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:59:07 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)) 17:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:59:07 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-oYUHPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:59:07 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)) 17:59:07 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)) 17:59:07 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)) 17:59:07 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)) 17:59:07 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)) 17:59:07 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)) 17:59:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:59:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:59:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:59:09 INFO - Downloading blessings-1.6.tar.gz 17:59:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:59:09 INFO - Installing collected packages: blessings 17:59:09 INFO - Running setup.py install for blessings 17:59:10 INFO - Successfully installed blessings 17:59:10 INFO - Cleaning up... 17:59:10 INFO - Return code: 0 17:59:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:59:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:59:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:59:10 INFO - Reading from file tmpfile_stdout 17:59:10 INFO - Current package versions: 17:59:10 INFO - blessings == 1.6 17:59:10 INFO - blobuploader == 1.2.4 17:59:10 INFO - docopt == 0.6.1 17:59:10 INFO - functools32 == 3.2.3-2 17:59:10 INFO - jsonschema == 2.5.1 17:59:10 INFO - manifestparser == 1.1 17:59:10 INFO - mozInstall == 1.12 17:59:10 INFO - mozcrash == 0.17 17:59:10 INFO - mozdebug == 0.1 17:59:10 INFO - mozdevice == 0.48 17:59:10 INFO - mozfile == 1.2 17:59:10 INFO - mozhttpd == 0.7 17:59:10 INFO - mozinfo == 0.9 17:59:10 INFO - mozleak == 0.1 17:59:10 INFO - mozlog == 3.4 17:59:10 INFO - moznetwork == 0.27 17:59:10 INFO - mozprocess == 0.23 17:59:10 INFO - mozprofile == 0.28 17:59:10 INFO - mozrunner == 6.12 17:59:10 INFO - mozscreenshot == 0.1 17:59:10 INFO - mozsystemmonitor == 0.3 17:59:10 INFO - moztest == 0.7 17:59:10 INFO - mozversion == 1.4 17:59:10 INFO - psutil == 3.1.1 17:59:10 INFO - requests == 1.2.3 17:59:10 INFO - wsgiref == 0.1.2 17:59:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:59:10 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')]} 17:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:10 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')]} 17:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:10 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 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:59:10 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 17:59:10 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 17:59:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:59:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:10 INFO - 'HOME': '/Users/cltbld', 17:59:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:10 INFO - 'LOGNAME': 'cltbld', 17:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:10 INFO - 'MOZ_NO_REMOTE': '1', 17:59:10 INFO - 'NO_EM_RESTART': '1', 17:59:10 INFO - 'PAGER': '/bin/cat', 17:59:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:10 INFO - 'PWD': '/builds/slave/test', 17:59:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:10 INFO - 'SHELL': '/bin/bash', 17:59:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:59:10 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:59:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:10 INFO - 'USER': 'cltbld', 17:59:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:10 INFO - 'XPC_FLAGS': '0x0', 17:59:10 INFO - 'XPC_SERVICE_NAME': '0', 17:59:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:10 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)) 17:59:10 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)) 17:59:10 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)) 17:59:10 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)) 17:59:10 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)) 17:59:10 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)) 17:59:10 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)) 17:59:10 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)) 17:59:10 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)) 17:59:10 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)) 17:59:10 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)) 17:59:10 INFO - Cleaning up... 17:59:10 INFO - Return code: 0 17:59:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:59:10 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')]} 17:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:10 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')]} 17:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:59:10 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 0x103d8e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d04570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc281d5b020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, '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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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 17:59:10 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 17:59:10 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 17:59:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:59:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:10 INFO - 'HOME': '/Users/cltbld', 17:59:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:10 INFO - 'LOGNAME': 'cltbld', 17:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:10 INFO - 'MOZ_NO_REMOTE': '1', 17:59:10 INFO - 'NO_EM_RESTART': '1', 17:59:10 INFO - 'PAGER': '/bin/cat', 17:59:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:10 INFO - 'PWD': '/builds/slave/test', 17:59:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:10 INFO - 'SHELL': '/bin/bash', 17:59:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:59:10 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:59:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:10 INFO - 'USER': 'cltbld', 17:59:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:10 INFO - 'XPC_FLAGS': '0x0', 17:59:10 INFO - 'XPC_SERVICE_NAME': '0', 17:59:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:59:10 INFO - Cleaning up... 17:59:11 INFO - Return code: 0 17:59:11 INFO - Running post-action listener: _resource_record_post_action 17:59:11 INFO - Running post-action listener: _start_resource_monitoring 17:59:11 INFO - Starting resource monitoring. 17:59:11 INFO - [mozharness: 2016-10-13 00:59:11.037354Z] Finished create-virtualenv step (success) 17:59:11 INFO - [mozharness: 2016-10-13 00:59:11.038357Z] Running install step. 17:59:11 INFO - Running pre-action listener: _resource_record_pre_action 17:59:11 INFO - Running main action method: install 17:59:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:59:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:59:11 INFO - Reading from file tmpfile_stdout 17:59:11 INFO - Detecting whether we're running mozinstall >=1.0... 17:59:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:59:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:59:11 INFO - Reading from file tmpfile_stdout 17:59:11 INFO - Output received: 17:59:11 INFO - Usage: mozinstall [options] installer 17:59:11 INFO - Options: 17:59:11 INFO - -h, --help show this help message and exit 17:59:11 INFO - -d DEST, --destination=DEST 17:59:11 INFO - Directory to install application into. [default: 17:59:11 INFO - "/builds/slave/test"] 17:59:11 INFO - --app=APP Application being installed. [default: firefox] 17:59:11 INFO - mkdir: /builds/slave/test/build/application 17:59:11 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'] 17:59:11 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 17:59:45 INFO - Reading from file tmpfile_stdout 17:59:45 INFO - Output received: 17:59:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:59:45 INFO - Running post-action listener: _resource_record_post_action 17:59:45 INFO - [mozharness: 2016-10-13 00:59:45.432305Z] Finished install step (success) 17:59:45 INFO - [mozharness: 2016-10-13 00:59:45.432431Z] Running run-tests step. 17:59:45 INFO - Running pre-action listener: _resource_record_pre_action 17:59:45 INFO - Running main action method: run_tests 17:59:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:59:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:59:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:59:45 INFO - 2016-10-12 17:59:45.446 screenresolution[1644:8466] starting screenresolution argv=screenresolution get 17:59:45 INFO - 2016-10-12 17:59:45.449 screenresolution[1644:8466] Display 0: 1600x1200x32@60 17:59:45 INFO - 2016-10-12 17:59:45.455 screenresolution[1645:8468] starting screenresolution argv=screenresolution list 17:59:45 INFO - Available Modes on Display 0 17:59:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:59:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:59:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:59:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:59:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:59:45 INFO - Intel Iris: 17:59:45 INFO - Chipset Model: Intel Iris 17:59:45 INFO - Type: GPU 17:59:45 INFO - Bus: Built-In 17:59:45 INFO - VRAM (Dynamic, Max): 1536 MB 17:59:45 INFO - Vendor: Intel (0x8086) 17:59:45 INFO - Device ID: 0x0a2e 17:59:45 INFO - Revision ID: 0x0009 17:59:45 INFO - Displays: 17:59:45 INFO - Display: 17:59:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:59:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:59:45 INFO - Display Serial Number: 1600x1200 60 17:59:45 INFO - Main Display: Yes 17:59:45 INFO - Mirror: Off 17:59:45 INFO - Online: Yes 17:59:45 INFO - Rotation: Supported 17:59:45 INFO - Return code: 0 17:59:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:59:45 INFO - Python 2.7.3 17:59:45 INFO - Return code: 0 17:59:45 INFO - grabbing minidump binary from tooltool 17:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:59:45 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 0x103ba2d30>, '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 0x1039c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103b9ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:59:45 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 17:59:45 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 17:59:45 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 17:59:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:59:45 INFO - Return code: 0 17:59:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:59:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:59:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:59:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:59:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:59:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:59:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0172 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:59:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ahjY9j2vwh/Render', 17:59:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:59:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:59:45 INFO - 'HOME': '/Users/cltbld', 17:59:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:59:45 INFO - 'LOGNAME': 'cltbld', 17:59:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:59:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:45 INFO - 'MOZ_NO_REMOTE': '1', 17:59:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:59:45 INFO - 'NO_EM_RESTART': '1', 17:59:45 INFO - 'PAGER': '/bin/cat', 17:59:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:59:45 INFO - 'PWD': '/builds/slave/test', 17:59:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:59:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:59:45 INFO - 'SHELL': '/bin/bash', 17:59:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qKeFBMiHVb/Listeners', 17:59:45 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 17:59:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:59:45 INFO - 'USER': 'cltbld', 17:59:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:59:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:59:45 INFO - 'XPC_FLAGS': '0x0', 17:59:45 INFO - 'XPC_SERVICE_NAME': '0', 17:59:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:59:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:59:46 INFO - mozversion application_buildid: 20161012144547 17:59:46 INFO - mozversion application_changeset: d68def1644c1849141ceaa31bc1b811becfbb8da 17:59:46 INFO - mozversion application_display_name: Nightly 17:59:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:59:46 INFO - mozversion application_name: Firefox 17:59:46 INFO - mozversion application_remotingname: firefox 17:59:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:59:46 INFO - mozversion application_vendor: Mozilla 17:59:46 INFO - mozversion application_version: 52.0a1 17:59:46 INFO - mozversion platform_buildid: 20161012144547 17:59:46 INFO - mozversion platform_changeset: d68def1644c1849141ceaa31bc1b811becfbb8da 17:59:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:59:46 INFO - mozversion platform_version: 52.0a1 17:59:46 INFO - using testdate: 1476320386 17:59:46 INFO - actual date: 1476320386 17:59:46 INFO - starting webserver on 'localhost:49272' 17:59:46 INFO - SUITE-START | Running 2 tests 17:59:46 INFO - TEST-START | dromaeo_css 17:59:46 INFO - operating with platform_type : mac_ 17:59:46 INFO - Initialising browser for dromaeo_css test... 17:59:46 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpsn7qwd/profile http://localhost:49272/getInfo.html) 17:59:49 INFO - PROCESS | 1655 | 2016-10-12 17:59:49.181 plugin-container[1658:8643] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 17:59:49 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:59:49 INFO - PROCESS | 1655 | 2016-10-12 17:59:49.183 plugin-container[1658:8643] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 17:59:49 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:59:49 INFO - PROCESS | 1655 | 2016-10-12 17:59:49.183 plugin-container[1658:8643] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:59:49 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 17:59:49 INFO - PROCESS | 1655 | colorDepth:24 17:59:49 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 17:59:49 INFO - PROCESS | 1655 | __metrics 17:59:49 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 17:59:49 INFO - TEST-INFO | 1655: exit 0 17:59:49 INFO - Browser initialized. 17:59:49 INFO - Running cycle 1/1 for dromaeo_css test... 17:59:49 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpsn7qwd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:59:52 INFO - PROCESS | 1660 | 2016-10-12 17:59:52.391 plugin-container[1661:8769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 17:59:52 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:59:52 INFO - PROCESS | 1660 | 2016-10-12 17:59:52.392 plugin-container[1661:8769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 17:59:52 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:59:52 INFO - PROCESS | 1660 | 2016-10-12 17:59:52.392 plugin-container[1661:8769] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:00:01 INFO - PROCESS | 1660 | RSS: Main: 239251456 18:00:01 INFO - PROCESS | 1660 | 18:00:51 INFO - PROCESS | 1660 | 1476320451650 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] 18:03:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 18:03:06 INFO - PROCESS | 1660 | RSS: Main: 253526016 18:03:06 INFO - PROCESS | 1660 | 18:06:08 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 18:06:09 INFO - PROCESS | 1660 | RSS: Main: 266084352 18:06:09 INFO - PROCESS | 1660 | 18:09:11 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 18:09:11 INFO - PROCESS | 1660 | RSS: Main: 269471744 18:09:11 INFO - PROCESS | 1660 | 18:12:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 18:12:14 INFO - PROCESS | 1660 | RSS: Main: 269500416 18:12:14 INFO - PROCESS | 1660 | 18:15:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 18:15:17 INFO - PROCESS | 1660 | RSS: Main: 276148224 18:15:17 INFO - PROCESS | 1660 | 18:18:20 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 18:18:21 INFO - PROCESS | 1660 | RSS: Main: 278630400 18:18:21 INFO - PROCESS | 1660 | 18:18:21 INFO - PROCESS | 1660 | __start_tp_report 18:18:21 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 18:18:21 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 18:18:21 INFO - PROCESS | 1660 | |i|pagename|runs| 18:18:21 INFO - PROCESS | 1660 | |0;dojo.html;3250.9109448334807;3278.639349671534;3306.30567580808;3349.012041447773;3351.0784534253085;4421;4730.668853180278;5199.610029247808;5266.420693723692;5441.102218134013;5390.595705322106;5562.137868630359;5573.665580065189;5657.745401456934;5815.767369305223;3998.9402808255836;5101.285819985205;5105.770240339194;5135.2040433732645;5244.895102097956;4434.379186913835;5220.321358223426;5247.475252474741;5263;5285.99566082445;5069.264956581312;5178.629303682312;5187.896242075138;5193.584513238932;5265.236540701596;5126.436092029874;5194.818181363672;5200.609954253446;5210.557102646242;5235.55497782689;5097.006083813658;5138.229265610151;5153.742312884341;5158.509941555546;5202.3497062867145;4450.933236001462;5456.91814622781;5491;5658.547316214693;5721.856953576153;5246.265522826808;5654.208410822488;5987.730552125165;6029.065494848305;6140.3552626974415;6128.908066379008;6132.936844521141;6137.6010559313945;6152.907706384408;6170.81487555374;5700.914486282709;5712.685802280915;5717.714114294268;5719.256496655408;5753.539716822645;5995.190649262294;6057.061155520813;6072.726727297282;6095.573309868267;6105.175801266861;5949.553783466257;5986.910196347058;6030.155778191057;6057.909131362945;6076.240469941257;6069.15031895535;6081.209442772411;6092.421219984094;6097.69511524422;6107.4197951194565;5883.352831188566;5973.850653733692;6016.398360163948;6047.969760151259;6081.969590152021;5998.850028749316;6102.69486525672;6107.114468402056;6108.3280839107665;6127.571070025145;4141.440905477722;4777.570018698334;4847.5394837490385;4856.3201151838775;4860.125177468019;4234.640055595247;4236.406903033578;4252.043290259667;4297.656187505055;4309.525952145262;5620.325560932714;5624.718764061862;5626.127950167731;5640.661560306313;5694.886102277931;4801.847556586394;5968.104784282305;6164.198654174928;6199.907001394983;6205.596636218631;5384.6769193848495;5489.066858634042;5500.80747173855;5602.243697100921;5603.859903502445;5242;5311.150215965427;5324;5351.143816989341;5364.436734142859;6218.502519798405;6224.968875155595;6239.875202495834;6260.843478912972;6370.872582548323;5179.8446046618665;5966.313873904556;6055.1522786810765;6163.876722465615;6174.876502470015;6120.203342909657;6139.676133582418;6174.166487524307;6229.470495007975;6248.4688801452385;6059.303180134165;6092.268927728701;6099.176611157437;6107.236595425572;6119.020956646915;5301.045811753921;5583.637063590853;5861.677607731615;6142.201513803356;6185.597936134137;4176.331786914079;4358.520562738159;4654.813807447664;5955.91066134017;6056.939430605637;5906.616069955439;5942.732577033958;5943.494802941798;5975.880482390241;5993.160957465871;5695.345035320991;5733.197352370588;5747.3103227612955;5876.206712093813;5921.467067963904;5927.347991720821;5931.555133364928;5943.375945525658;5973.402659733992;5977.671228082323;5439.673619582838;5528.19841123043;5721.199032135588;5787.247657804627;5806.593538452268 18:18:21 INFO - PROCESS | 1660 | |1;ext.html;487.1158846693254;490.7227416509674;499.1464595541624;509.8317555206782;534.7887584404159;29987.250318742015;39026.829195124155;39354.21291574167;39373.60626393735;41321.38017929733;40597.985050373754;40828.795856020675;40994.59005409945;41087;41133.7943310283;9478.194353479963;9630.085141911542;9652.855207171899;9670.468124253199;9711.36876103051;8348.290395316375;9494.335396522247;9521.238300935944;9531.237500999903;9617.90382096177;9614.567344469515;9640.662576809813;9660.855087173699;9665.98507156749;9667.178289845371;9530.475823829687;9580.80838383232;9621.278404119648;9637.18083962864;9645.03549645037;8164.959175204086;9475;9595.28035397348;9608.423494590346;9612.66355677548;38723.8063809682;39008.21983560341;39116.804415978026;39129.217415651816;39209.03954802248;3259.103746469716;3273.5580696605894;3322.96677033229;3351.145457908242;3424.332255210235;28440.573391399146;29331.853340733374;29445.70554294451;29667;30332;3208.2139875730595;3208.911219792573;3218.7585931055264;3244.6917542833394;3327.3012667339885;392150;393314;394850;396175;396341;55370;55404.44595554074;55655.443445565834;55791.721041394536;56048.43951560514;51027;58903;58987.705061474415;59216.70391648014;59398;15849.920750396404;15975.760363594556;16008.839911600735;16041;16073.357065717475;12245.510179592717;12263.570775022832;12309.26144431337;12324.50701971929;12332.630021099381;14568.781468277984;16308;18093.914365138135;18175.909120454577;18203.817961820478;7484.401247900155;7513.323800857949;7532.472726909064;7563.432742544332;7598.088229412396;838.7525679924437;949.6506002508495;988.1551273660916;1034.9482525873675;1048.648702684594;24774.876125619616;25270.241892743248;27465.725342746715;29846.25384365408;30113.849430752707;869.9869501957476;986.4377304936118;1030.845373194027;1114.0530549033258;1116.2855772305732;14890.404383824525;15257.771133433007;15388;15392.461263855712;15559.45541906028;699268;702285;704981;705558;709804;52817;54619.180712289344;55079.724601376736;55640.443595564335;55892.44107558954;1300.9414576344088;1365.7200273943818;1367.3095086981011;1368.056041331498;1370.5751217122524;26938.730612693624;27986.72013279841;28884;29183.832646693896;29224;46638.76680616575;46971;47018.29472557983;47187.528124718316;47247.29129063136;521093;521248;521638;522855;524261;22871;24925;25424.364390890376;25786.742132579184;25832.354191145372;36177.63822361848;39048.80475597717;45648.54351456443;46786.29820552626;46833.53166468428;30875.536866946564;34401.82799086089;37191.814040929625;37803.05492362713;38846.4173037399;37534.62465375311;38664;38691.22617547577;38754.2249155021;39025;36788.44817327688;36987.81506092452;37024.81487592545;37600;37906.62093379141;32739.672603273662;34137.82931085329;34276.828615856764;34452.31095378028;34554.82722586371;22407.663885042064;25299.114530991326;25318.873405632854;25740.841662124876;25944.351391215372 18:18:21 INFO - PROCESS | 1660 | |2;jquery.html;354.49838478552846;369.5343866727922;373.0822177443489;378.22086501806297;396.179907591286;19591.608167836635;19728.506787330323;20123.094460749264;20466.283680071218;20618.278360257395;11556.017738492217;11751.7649647007;18695.252189912386;19523.023848807574;20163.798362016376;5081.872953176173;5339.759710813003;5340.092184328654;5355.7857685692725;5365.195220716884;4886.218205087187;5149.485051494858;5869.8826023479505;5877.14781356703;5881.264841894763;4985.825496107637;5756.654600723949;5831.825045248649;5849.093390524455;5870.647761134345;5292.338457692788;5301.602379821509;5336.4129945705945;5339.305890234284;5339.41266460691;5034.446210916796;5188.806574487889;5205.609579281569;5212.061828870802;5228.973855130738;20427.795722042887;23263.25525585588;23634.29097127089;24976.250712478653;25161;2043.3563427520282;2072.367927782026;2119.5760847830343;2206.4042708468783;2371.9407014824596;9866.358686685342;10237.846432303522;10401.739956501073;11804.232724872856;14806.62983425423;1839.6688596052704;1861.1438738180452;1870.7193920911836;1909.767936367568;1928.5757133430764;108958;119543;123417;124518.37740811329;124912.37543812314;9292.258783658837;9624.941256461783;10016.899831001743;10020.899791002143;10125.443100629536;12244.387780610934;12277.754444911052;12287.201331913397;12391.62825115248;12439.626811195678;7560.508566943131;7762.495437796638;7791.259830316111;7890.763277101696;7903.683852645945;7883.802904927308;7888.171741967126;7894.447388682852;8073.39449541275;8094.311983481352;3358.429067058606;3490.616032236452;3633.109888077438;3848.0572259795968;3873.6320049595242;1523.7333466643292;1651.5788473939308;1654.859336956372;1931.4495368819819;1954.5797653504565;896.7220161749879;961.778790878088;997;998.7453199434098;1015.2385710716962;19176.424707258804;21649.67525487119;21689;21689;21885.562288754136;775.5811861594789;803.1687203744096;854.9444286121383;959.4579062829479;994.4033579852031;10734.78530429387;11350.772984540263;11386.259893106922;11409;11436.198131457846;571156.1442192845;578223;578856.1057194687;579456;583444;102075.4896225529;122618;123658;124461.75538244502;125694;968.2109081098882;1018.4703953944056;1027.5941003303803;1028.8971102889652;1029.7580068683853;18989.90505047466;19915.10382032783;19916;19953.401397958085;20057;53249;53556.46443535515;54836;55634.16548751691;55693.72153139208;76964.615176926;77028.22971770435;77699;78394.82407763774;79185;7892.763217103496;7999.800004999922;8233.752987410388;8253.380996425172;8259.628316725642;10865.293755905841;12702.745945080862;12742.745145097231;12770.169938953939;12922.095453318178;11126.721831954266;11264;11402.600908968147;11468.426578670866;11792;10892.18308626872;10994.175436842428;11129.387883666475;11177.88822111801;11219;11379.260348077349;11421.657350279505;11592.942035289769;11643.767124657628;11824.349660768741;7945.443818932619;7952.403569732409;8321.00147982246;8352.707655232038;8379.371547134111;10404.323718958243;11020.50407731638;11123.27698699582;11295.717607059889;11555.768884622428 18:18:21 INFO - PROCESS | 1660 | |3;mootools.html;1481.5407223760635;1522.101959843692;1669.5492217101382;1774.2636805725624;1790.9015004174764;7969.641366138523;8218.342532597393;8437.66249350026;9063.456192628439;9075.319351048673;7309.6345182740915;8698.999615044271;8742.562871856398;8853.734387968356;8934.86597701035;2348.9060437582475;2373.4896997145697;2373.95252094958;2375.334906226251;2381.583222935988;2337.4857531343077;2368.810495160392;2385.6063749481314;2387.689600351952;2393.5930891748358;2333.88330583471;2364.290712786166;2367.5264947010583;2385.9263331500806;2388.22382725614;2323.2101085630884;2327.034280773477;2341.1922886604093;2352.9882350588223;2366.219147681261;2331.603627383349;2347.319277409549;2351.988240058806;2352.8941197646145;2355.6819829322994;8961.32790040743;8965.058668839809;8966.775830604221;8991.505467199302;8992.505412202327;4741.407324084489;5192.688438693691;5335.519803217729;5385.5960802939935;5427.213054107172;3273.312604353089;3392.8982130536124;3613.9096522586883;3635.309291234657;3727.8136093195503;1043.8048434542482;1044.5456226541478;1061.75048863517;1062.1555863088863;1068.2735739697002;10350.223733220038;11661.242019268806;11689.76620467586;11698.356590387526;11719.589814356541;4083.2037752637953;4571.451425828922;4634.443866736014;4826.8793280168275;4830.130576496264;9936.801263974825;9982.101610855058;10011.249156313306;10018.499075046218;10019.599216031424;5374.811881584125;5393.6763794172475;5409.26974858397;5416.91874621881;5424.186372044225;3866.3620502616754;4418.094290670405;4589.128065667513;4859.465458799529;4944.505549445099;3032.120685001325;3636.8545258189374;3857.40210267409;4024.4164596133396;4072.8392408164123;1127.720037757135;1269.847618285793;1290.270996886756;1290.6386211860695;1298.104308027458;817.6320655704957;893.647009431284;912.2063804490136;933.2254228989922;934.3926447808842;5677.290338707661;5771.480566749013;5995.250593675791;5997.670128142907;6092.086187071975;440.8126546217845;446.5802145982766;446.99203296566037;955.7849483866214;968.5544649461186;3439.7076248519156;3848.230353929225;3977.343738283209;4049.5140583129623;4081.0409553754844;9921.908590055089;9980.850287245697;10395.428251446243;10786.352818830896;10810.729731756768;10529.315594486334;10598.364098154136;10667.893321066846;10687.78624427507;10752.193585480964;8129.268365847102;8256.298214651702;8288.129746376664;8302.54336011513;8307.335413166933;5776.075827867521;5846;5940.495057920125;5956.136360227656;5956.404359564182;9532.380395274286;9999.3000489965;10018.64934727281;10070.597176112873;10078.193744500275;10159.949200253952;10196.286259961731;10291.691249262534;10304.227182961458;10323.070923616911;6496.123023391781;6718.93281067183;6866.244713081657;7045.330693584101;7139.32176443237;8168.428210025241;8436.409451338348;8527.445716028686;8532.23209911111;8535.914640853513;7379.926200737924;7527.209642987407;7543.622818859145;7594.278543538355;7639.656215470206;7289.671964761493;7383.8154046149275;7438.962805185939;7515.060617422822;7550.735724249625;6887.414569761426;6996.895046574204;7047.436205103682;7051.682674279772;7051.753188638373;4154.958450415457;4931.211006239128;5050.242463630485;5115.235243840869;5154.664946778447;4953.108440480748;5065.670731402447;5129.076766181974;5227.817026404057;5237.135872580981 18:18:21 INFO - PROCESS | 1660 | |4;prototype.html;306.81131104370814;313.474929493099;313.7552708887067;318.1838584031959;320.8139279218054;4604.470485894123;5426.538744206742;5462.945370546293;5494.423085576017;5568.832935011951;5329.360476742796;5345.209354866124;5380.273663055486;5384.865378365543;5419.783208671658;3210.6628803975536;3632.600413954463;3766.6986641068784;4025.154717509312;4084.5098588169312;3638;4103.569125241851;4133.503979522447;4152.792360381969;4324.351347297915;4106.589341065885;4112;4146.3780432935;4146.688998325122;4152.563980782004;3510.069461796015;3640.468745317038;3777.3389656810223;4042.2618274142105;4173.812178451962;3473.322702073097;3487.825608719579;3489.354469423165;3501.457274122515;3515.402381595135;4613.723176609415;4827.62066553414;5223.843284701465;5251.474852514756;5294.385212510183;4528.773561321921;4617.291602107208;4690.319903613991;4692.554207350297;4706.3411122442885;5675.744591493392;5697.316322041382;5699.772009119631;5710.71446427677;5715.599908006441;3872.8838134856;3875.976738144252;3878.0803502266276;3878.4957955465893;3915.138669492707;4809.95190048102;4989.800407983676;5059.3422855028975;5193.558547523465;5206.323177986876;3522.5949015862993;3888.475055867478;3888.6111388860886;3891.64975152232;3893.3381325174787;3389.6440873708398;3980.4825372701944;3990.4812374391145;3996.1208534122447;4004.3392840181045;4614.515475875052;4641.234196357564;4661.790219440133;4682.648801339845;4691.81232750693;4878.390201224847;5029.396472423333;5220.9738951305;5688.236646639555;5766.279215098113;1331.8002299654936;1356.409961666678;1358.2665360705307;1360.394624392136;1365.7609918264263;703.9225685174627;758.1319389299222;774.8295375017487;809.7449303469389;814.5764202614607;5072.930876819545;5168.2764412982215;5316.7075810830775;5335.653182543122;5350.357957045179;5186.377634683787;5622.718864056781;5904.9704751475965;5924.585279030426;5968.015277479254;4366.432363792686;5071.974640126849;5130.666506677054;5296;5475.835724928259;2468.2101727447402;3047.4362242985117;3061.663217046123;3076.8153910765427;3087.9073627791204;6014.278286605635;6044.486218671462;6045.455908968126;6095.988323853117;6115.877682446326;5435.8856434430845;5459.972700136554;5636.098224284095;5767.423257674199;6039.66781826995;4772.642051846056;5439.564834813205;5657.462541058675;6054.091886217103;6091.360407157274;5163.540427913232;5616.8876622466505;5805.287440205234;5848.561357898089;5894.970525147347;4425.822967081281;5046.520580544842;5521.840413513047;5739.454751798572;5763.682997435181;5095.490450954875;5824.679642619697;6067.332593414632;6094.506845822802;6111.663858487826;3235.53084802707;3760.777747232106;3846.4614953907035;3869.7871617061332;3937.4684417603257;5011.9474910269255;5045.066662667418;5317.042932272228;5528.530074943675;5678.460546248265;4605.40129783126;5006.724630145377;5558.027345214652;5596.8320950369925;5633.521150702072;5348.973255133855;5541.612087153861;5543.667379957215;5545.975718862666;5615.775368985358;4423.579759922803;5432.29380180574;5452.509274165185;5456.372517160577;5480.753366098615;4568.954310456986;4788.497207793132;5310.309659744208;5334;5623.831285061455;5327.760250788646;5470.632341914521;5472.890542189054;5479.260299859628;5483.396826349019 18:18:21 INFO - PROCESS | 1660 | |5;yui.html;584.4039080138259;699.5103427600677;721.592300350302;747.8653842308385;776.4060493722307;14964.401423943043;15393.846061539381;15959.361625534979;15970.121643309614;16055.438059667915;14448.783268250958;14454.638634034154;14604.634884127901;14606.853931460682;14674;689.4036658290593;843.1105184030845;867.3278209387712;871.1245198575455;871.755908345661;705.9435245180399;845.4251109245711;865.16079402979;872.6640243506264;877.692807517367;862.7023676831515;863.365426411587;913.821804748071;915.9587818548148;918.8437965545874;891.5096696816757;908.8682141089505;911.9407238529475;913.196387179278;915.922146617535;650.8437974886041;681.2029924987727;681.3186813186813;682.1302838880467;682.3381320119477;11267.774644507146;12174.26954382731;14137.081089729134;14398.76027893726;14399.992000559965;10802.243842930999;12176.330301833395;14413.351399187062;14545.490907818175;14612.050216735877;11485.94257028718;11854.703632409173;11873.762524749543;11889.524419023228;12064.406152677217;3766.1714422826935;3774.3772277574167;3886.6890648748317;3912.902177445558;3959.78221197834;87605;112853;114995.42502287435;115091;118679.40660296644;11055.115590752881;11057.612983545698;11114.444277786079;11118.722031949266;11493.827592586118;23042;23477.1782987598;23477.765222347556;23508.882455587613;23542.764572354055;6259.6870156492;7784.688612455551;7839.294463498312;7869.291763741291;7951.324137448381;4277.572242775697;4355.303151495746;4382.233109205877;4447.510773814878;4495.145922274757;3565;3668.9633103669157;3677.7057835373107;3690.317291301118;3701.2227432239533;660.4485254812286;662.3442791636245;669.0733334331888;706.2937062937064;718.7340683946973;586.5219845825638;644.1818890009662;662.7400564076572;662.9801255282235;683.2893790457967;7344.889826652605;9329.300302477206;9440.339176257596;9807.411555306704;10062.490626405952;550.6179489481351;565.6973519167226;621.9458018658331;672.1363048482676;699.3286445012844;4258.339957306623;4266.847951053198;4378.890527736832;4692.328043648591;4783.6651434399255;87353.78216792295;92729;93231.53384233035;97446;143708.5629143695;100261;131993.34003329923;132807;134398;136955;75340.11649708691;106132;108151;116628;116759;7720.498167619086;8800.91199088001;8997.865032024394;9046.773830654287;9205.447673139633;58693;62800.685996571556;63051.80326557518;63456;64931.67534162299;94112;95577;96613;97498.51250743702;97765.51117244369;11629.709257268636;11805;12152.392380381123;12175.086868484723;12299.323537205539;13125.934370328087;13233.933830330787;13323.467061317438;13397.330133493091;13874.722505550035;12245.571405000783;12276.780158015104;12321.815172772236;12407;12733.426995785027;12410.193337433038;12434.253944763343;12598.496060157491;12763.170393924365;12776.233425994471;10531.473426328806;10853.294535855144;11073.05879000294;11404.657860264206;12602.621921342374;10202.948985255027;10222.335548189641;10351.482425878525;10449.843252351373;10834.945825270823;8973.37186396972;9780.559874805793;10266.486675666336;10457.68626941193;10711.410872402093 18:18:21 INFO - PROCESS | 1660 | __end_tp_report 18:18:21 INFO - PROCESS | 1660 | __start_cc_report 18:18:21 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,373 18:18:21 INFO - PROCESS | 1660 | __end_cc_report 18:18:21 INFO - PROCESS | 1660 | __startTimestamp1476321501074__endTimestamp 18:18:21 INFO - PROCESS | 1660 | 18:18:21 INFO - PROCESS | 1660 | ------- Summary: start ------- 18:18:21 INFO - PROCESS | 1660 | Number of tests: 6 18:18:21 INFO - PROCESS | 1660 | 18:18:21 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5536.71 Median:5717.71 stddev:672.83 (11.8%) stddev-sans-first:650.56 18:18:21 INFO - PROCESS | 1660 | Values: 3250.9 3278.6 3306.3 3349.0 3351.1 4421.0 4730.7 5199.6 5266.4 5441.1 5390.6 5562.1 5573.7 5657.7 5815.8 3998.9 5101.3 5105.8 5135.2 5244.9 4434.4 5220.3 5247.5 5263.0 5286.0 5069.3 5178.6 5187.9 5193.6 5265.2 5126.4 5194.8 5200.6 5210.6 5235.6 5097.0 5138.2 5153.7 5158.5 5202.3 4450.9 5456.9 5491.0 5658.5 5721.9 5246.3 5654.2 5987.7 6029.1 6140.4 6128.9 6132.9 6137.6 6152.9 6170.8 5700.9 5712.7 5717.7 5719.3 5753.5 5995.2 6057.1 6072.7 6095.6 6105.2 5949.6 5986.9 6030.2 6057.9 6076.2 6069.2 6081.2 6092.4 6097.7 6107.4 5883.4 5973.9 6016.4 6048.0 6082.0 5998.9 6102.7 6107.1 6108.3 6127.6 4141.4 4777.6 4847.5 4856.3 4860.1 4234.6 4236.4 4252.0 4297.7 4309.5 5620.3 5624.7 5626.1 5640.7 5694.9 4801.8 5968.1 6164.2 6199.9 6205.6 5384.7 5489.1 5500.8 5602.2 5603.9 5242.0 5311.2 5324.0 5351.1 5364.4 6218.5 6225.0 6239.9 6260.8 6370.9 5179.8 5966.3 6055.2 6163.9 6174.9 6120.2 6139.7 6174.2 6229.5 6248.5 6059.3 6092.3 6099.2 6107.2 6119.0 5301.0 5583.6 5861.7 6142.2 6185.6 4176.3 4358.5 4654.8 5955.9 6056.9 5906.6 5942.7 5943.5 5975.9 5993.2 5695.3 5733.2 5747.3 5876.2 5921.5 5927.3 5931.6 5943.4 5973.4 5977.7 5439.7 5528.2 5721.2 5787.2 5806.6 18:18:21 INFO - PROCESS | 1660 | 18:18:21 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:66773.76 Median:39082.80 stddev:148799.76 (380.7%) stddev-sans-first:149133.95 18:18:21 INFO - PROCESS | 1660 | Values: 487.1 490.7 499.1 509.8 534.8 29987.3 39026.8 39354.2 39373.6 41321.4 40598.0 40828.8 40994.6 41087.0 41133.8 9478.2 9630.1 9652.9 9670.5 9711.4 8348.3 9494.3 9521.2 9531.2 9617.9 9614.6 9640.7 9660.9 9666.0 9667.2 9530.5 9580.8 9621.3 9637.2 9645.0 8165.0 9475.0 9595.3 9608.4 9612.7 38723.8 39008.2 39116.8 39129.2 39209.0 3259.1 3273.6 3323.0 3351.1 3424.3 28440.6 29331.9 29445.7 29667.0 30332.0 3208.2 3208.9 3218.8 3244.7 3327.3 392150.0 393314.0 394850.0 396175.0 396341.0 55370.0 55404.4 55655.4 55791.7 56048.4 51027.0 58903.0 58987.7 59216.7 59398.0 15849.9 15975.8 16008.8 16041.0 16073.4 12245.5 12263.6 12309.3 12324.5 12332.6 14568.8 16308.0 18093.9 18175.9 18203.8 7484.4 7513.3 7532.5 7563.4 7598.1 838.8 949.7 988.2 1034.9 1048.6 24774.9 25270.2 27465.7 29846.3 30113.8 870.0 986.4 1030.8 1114.1 1116.3 14890.4 15257.8 15388.0 15392.5 15559.5 699268.0 702285.0 704981.0 705558.0 709804.0 52817.0 54619.2 55079.7 55640.4 55892.4 1300.9 1365.7 1367.3 1368.1 1370.6 26938.7 27986.7 28884.0 29183.8 29224.0 46638.8 46971.0 47018.3 47187.5 47247.3 521093.0 521248.0 521638.0 522855.0 524261.0 22871.0 24925.0 25424.4 25786.7 25832.4 36177.6 39048.8 45648.5 46786.3 46833.5 30875.5 34401.8 37191.8 37803.1 38846.4 37534.6 38664.0 38691.2 38754.2 39025.0 36788.4 36987.8 37024.8 37600.0 37906.6 32739.7 34137.8 34276.8 34452.3 34554.8 22407.7 25299.1 25318.9 25740.8 25944.4 18:18:21 INFO - PROCESS | 1660 | 18:18:21 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:34046.21 Median:12045.98 stddev:96629.17 (802.2%) stddev-sans-first:96867.12 18:18:21 INFO - PROCESS | 1660 | Values: 354.5 369.5 373.1 378.2 396.2 19591.6 19728.5 20123.1 20466.3 20618.3 11556.0 11751.8 18695.3 19523.0 20163.8 5081.9 5339.8 5340.1 5355.8 5365.2 4886.2 5149.5 5869.9 5877.1 5881.3 4985.8 5756.7 5831.8 5849.1 5870.6 5292.3 5301.6 5336.4 5339.3 5339.4 5034.4 5188.8 5205.6 5212.1 5229.0 20427.8 23263.3 23634.3 24976.3 25161.0 2043.4 2072.4 2119.6 2206.4 2371.9 9866.4 10237.8 10401.7 11804.2 14806.6 1839.7 1861.1 1870.7 1909.8 1928.6 108958.0 119543.0 123417.0 124518.4 124912.4 9292.3 9624.9 10016.9 10020.9 10125.4 12244.4 12277.8 12287.2 12391.6 12439.6 7560.5 7762.5 7791.3 7890.8 7903.7 7883.8 7888.2 7894.4 8073.4 8094.3 3358.4 3490.6 3633.1 3848.1 3873.6 1523.7 1651.6 1654.9 1931.4 1954.6 896.7 961.8 997.0 998.7 1015.2 19176.4 21649.7 21689.0 21689.0 21885.6 775.6 803.2 854.9 959.5 994.4 10734.8 11350.8 11386.3 11409.0 11436.2 571156.1 578223.0 578856.1 579456.0 583444.0 102075.5 122618.0 123658.0 124461.8 125694.0 968.2 1018.5 1027.6 1028.9 1029.8 18989.9 19915.1 19916.0 19953.4 20057.0 53249.0 53556.5 54836.0 55634.2 55693.7 76964.6 77028.2 77699.0 78394.8 79185.0 7892.8 7999.8 8233.8 8253.4 8259.6 10865.3 12702.7 12742.7 12770.2 12922.1 11126.7 11264.0 11402.6 11468.4 11792.0 10892.2 10994.2 11129.4 11177.9 11219.0 11379.3 11421.7 11592.9 11643.8 11824.3 7945.4 7952.4 8321.0 8352.7 8379.4 10404.3 11020.5 11123.3 11295.7 11555.8 18:18:21 INFO - PROCESS | 1660 | 18:18:21 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5672.22 Median:4828.50 stddev:3199.07 (66.3%) stddev-sans-first:3192.55 18:18:21 INFO - PROCESS | 1660 | Values: 1481.5 1522.1 1669.5 1774.3 1790.9 7969.6 8218.3 8437.7 9063.5 9075.3 7309.6 8699.0 8742.6 8853.7 8934.9 2348.9 2373.5 2374.0 2375.3 2381.6 2337.5 2368.8 2385.6 2387.7 2393.6 2333.9 2364.3 2367.5 2385.9 2388.2 2323.2 2327.0 2341.2 2353.0 2366.2 2331.6 2347.3 2352.0 2352.9 2355.7 8961.3 8965.1 8966.8 8991.5 8992.5 4741.4 5192.7 5335.5 5385.6 5427.2 3273.3 3392.9 3613.9 3635.3 3727.8 1043.8 1044.5 1061.8 1062.2 1068.3 10350.2 11661.2 11689.8 11698.4 11719.6 4083.2 4571.5 4634.4 4826.9 4830.1 9936.8 9982.1 10011.2 10018.5 10019.6 5374.8 5393.7 5409.3 5416.9 5424.2 3866.4 4418.1 4589.1 4859.5 4944.5 3032.1 3636.9 3857.4 4024.4 4072.8 1127.7 1269.8 1290.3 1290.6 1298.1 817.6 893.6 912.2 933.2 934.4 5677.3 5771.5 5995.3 5997.7 6092.1 440.8 446.6 447.0 955.8 968.6 3439.7 3848.2 3977.3 4049.5 4081.0 9921.9 9980.9 10395.4 10786.4 10810.7 10529.3 10598.4 10667.9 10687.8 10752.2 8129.3 8256.3 8288.1 8302.5 8307.3 5776.1 5846.0 5940.5 5956.1 5956.4 9532.4 9999.3 10018.6 10070.6 10078.2 10159.9 10196.3 10291.7 10304.2 10323.1 6496.1 6718.9 6866.2 7045.3 7139.3 8168.4 8436.4 8527.4 8532.2 8535.9 7379.9 7527.2 7543.6 7594.3 7639.7 7289.7 7383.8 7439.0 7515.1 7550.7 6887.4 6996.9 7047.4 7051.7 7051.8 4155.0 4931.2 5050.2 5115.2 5154.7 4953.1 5065.7 5129.1 5227.8 5237.1 18:18:21 INFO - PROCESS | 1660 | 18:18:21 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4532.63 Median:5084.21 stddev:1395.58 (27.4%) stddev-sans-first:1362.98 18:18:21 INFO - PROCESS | 1660 | Values: 306.8 313.5 313.8 318.2 320.8 4604.5 5426.5 5462.9 5494.4 5568.8 5329.4 5345.2 5380.3 5384.9 5419.8 3210.7 3632.6 3766.7 4025.2 4084.5 3638.0 4103.6 4133.5 4152.8 4324.4 4106.6 4112.0 4146.4 4146.7 4152.6 3510.1 3640.5 3777.3 4042.3 4173.8 3473.3 3487.8 3489.4 3501.5 3515.4 4613.7 4827.6 5223.8 5251.5 5294.4 4528.8 4617.3 4690.3 4692.6 4706.3 5675.7 5697.3 5699.8 5710.7 5715.6 3872.9 3876.0 3878.1 3878.5 3915.1 4810.0 4989.8 5059.3 5193.6 5206.3 3522.6 3888.5 3888.6 3891.6 3893.3 3389.6 3980.5 3990.5 3996.1 4004.3 4614.5 4641.2 4661.8 4682.6 4691.8 4878.4 5029.4 5221.0 5688.2 5766.3 1331.8 1356.4 1358.3 1360.4 1365.8 703.9 758.1 774.8 809.7 814.6 5072.9 5168.3 5316.7 5335.7 5350.4 5186.4 5622.7 5905.0 5924.6 5968.0 4366.4 5072.0 5130.7 5296.0 5475.8 2468.2 3047.4 3061.7 3076.8 3087.9 6014.3 6044.5 6045.5 6096.0 6115.9 5435.9 5460.0 5636.1 5767.4 6039.7 4772.6 5439.6 5657.5 6054.1 6091.4 5163.5 5616.9 5805.3 5848.6 5895.0 4425.8 5046.5 5521.8 5739.5 5763.7 5095.5 5824.7 6067.3 6094.5 6111.7 3235.5 3760.8 3846.5 3869.8 3937.5 5011.9 5045.1 5317.0 5528.5 5678.5 4605.4 5006.7 5558.0 5596.8 5633.5 5349.0 5541.6 5543.7 5546.0 5615.8 4423.6 5432.3 5452.5 5456.4 5480.8 4569.0 4788.5 5310.3 5334.0 5623.8 5327.8 5470.6 5472.9 5479.3 5483.4 18:18:21 INFO - PROCESS | 1660 | 18:18:21 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:23265.74 Median:3899.80 stddev:36377.45 (932.8%) stddev-sans-first:36439.64 18:18:21 INFO - PROCESS | 1660 | Values: 584.4 699.5 721.6 747.9 776.4 14964.4 15393.8 15959.4 15970.1 16055.4 14448.8 14454.6 14604.6 14606.9 14674.0 689.4 843.1 867.3 871.1 871.8 705.9 845.4 865.2 872.7 877.7 862.7 863.4 913.8 916.0 918.8 891.5 908.9 911.9 913.2 915.9 650.8 681.2 681.3 682.1 682.3 11267.8 12174.3 14137.1 14398.8 14400.0 10802.2 12176.3 14413.4 14545.5 14612.1 11485.9 11854.7 11873.8 11889.5 12064.4 3766.2 3774.4 3886.7 3912.9 3959.8 87605.0 112853.0 114995.4 115091.0 118679.4 11055.1 11057.6 11114.4 11118.7 11493.8 23042.0 23477.2 23477.8 23508.9 23542.8 6259.7 7784.7 7839.3 7869.3 7951.3 4277.6 4355.3 4382.2 4447.5 4495.1 3565.0 3669.0 3677.7 3690.3 3701.2 660.4 662.3 669.1 706.3 718.7 586.5 644.2 662.7 663.0 683.3 7344.9 9329.3 9440.3 9807.4 10062.5 550.6 565.7 621.9 672.1 699.3 4258.3 4266.8 4378.9 4692.3 4783.7 87353.8 92729.0 93231.5 97446.0 143708.6 100261.0 131993.3 132807.0 134398.0 136955.0 75340.1 106132.0 108151.0 116628.0 116759.0 7720.5 8800.9 8997.9 9046.8 9205.4 58693.0 62800.7 63051.8 63456.0 64931.7 94112.0 95577.0 96613.0 97498.5 97765.5 11629.7 11805.0 12152.4 12175.1 12299.3 13125.9 13233.9 13323.5 13397.3 13874.7 12245.6 12276.8 12321.8 12407.0 12733.4 12410.2 12434.3 12598.5 12763.2 12776.2 10531.5 10853.3 11073.1 11404.7 12602.6 10202.9 10222.3 10351.5 10449.8 10834.9 8973.4 9780.6 10266.5 10457.7 10711.4 18:18:21 INFO - PROCESS | 1660 | -------- Summary: end -------- 18:18:21 INFO - PROCESS | 1660 | 18:18:21 INFO - TEST-INFO | 1660: exit 0 18:18:21 INFO - TEST-OK | dromaeo_css | took 1115758ms 18:18:21 INFO - TEST-START | kraken 18:18:21 INFO - operating with platform_type : mac_ 18:18:21 INFO - Initialising browser for kraken test... 18:18:21 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpoac9To/profile http://localhost:49272/getInfo.html) 18:18:24 INFO - PROCESS | 1667 | 2016-10-12 18:18:24.389 plugin-container[1668:10893] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 18:18:24 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:18:24 INFO - PROCESS | 1667 | 2016-10-12 18:18:24.392 plugin-container[1668:10893] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 18:18:24 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:18:24 INFO - PROCESS | 1667 | 2016-10-12 18:18:24.392 plugin-container[1668:10893] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:18:24 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 18:18:24 INFO - PROCESS | 1667 | colorDepth:24 18:18:24 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 18:18:24 INFO - PROCESS | 1667 | __metrics 18:18:24 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 18:18:25 INFO - TEST-INFO | 1667: exit 0 18:18:25 INFO - Browser initialized. 18:18:25 INFO - Running cycle 1/1 for kraken test... 18:18:25 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpoac9To/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:18:27 INFO - PROCESS | 1669 | 2016-10-12 18:18:27.620 plugin-container[1670:11007] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 18:18:27 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:18:27 INFO - PROCESS | 1669 | 2016-10-12 18:18:27.620 plugin-container[1670:11007] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 18:18:27 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:18:27 INFO - PROCESS | 1669 | 2016-10-12 18:18:27.620 plugin-container[1670:11007] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:18:37 INFO - PROCESS | 1669 | RSS: Main: 239374336 18:18:37 INFO - PROCESS | 1669 | 18:18:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 18:18:40 INFO - PROCESS | 1669 | RSS: Main: 252805120 18:18:40 INFO - PROCESS | 1669 | 18:18:43 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 18:18:43 INFO - PROCESS | 1669 | RSS: Main: 260317184 18:18:43 INFO - PROCESS | 1669 | 18:18:47 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 18:18:48 INFO - PROCESS | 1669 | RSS: Main: 260997120 18:18:48 INFO - PROCESS | 1669 | 18:18:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 18:18:50 INFO - PROCESS | 1669 | RSS: Main: 262299648 18:18:50 INFO - PROCESS | 1669 | 18:18:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:18:53 INFO - PROCESS | 1669 | RSS: Main: 262516736 18:18:53 INFO - PROCESS | 1669 | 18:18:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 18:18:56 INFO - PROCESS | 1669 | RSS: Main: 263303168 18:18:56 INFO - PROCESS | 1669 | 18:18:59 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 18:19:00 INFO - PROCESS | 1669 | RSS: Main: 264486912 18:19:00 INFO - PROCESS | 1669 | 18:19:03 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 18:19:03 INFO - PROCESS | 1669 | RSS: Main: 264921088 18:19:03 INFO - PROCESS | 1669 | 18:19:05 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:19:05 INFO - PROCESS | 1669 | RSS: Main: 264822784 18:19:05 INFO - PROCESS | 1669 | 18:19:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:19:08 INFO - PROCESS | 1669 | RSS: Main: 264871936 18:19:08 INFO - PROCESS | 1669 | 18:19:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:19:11 INFO - PROCESS | 1669 | RSS: Main: 264720384 18:19:11 INFO - PROCESS | 1669 | 18:19:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:19:15 INFO - PROCESS | 1669 | RSS: Main: 264933376 18:19:15 INFO - PROCESS | 1669 | 18:19:18 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:19:19 INFO - PROCESS | 1669 | RSS: Main: 265314304 18:19:19 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 18:19:21 INFO - PROCESS | 1669 | RSS: Main: 266977280 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | __start_tp_report 18:19:21 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 18:19:21 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 18:19:21 INFO - PROCESS | 1669 | |i|pagename|runs| 18:19:21 INFO - PROCESS | 1669 | |0;ai-astar;108;118;118;117;117;116;119;116;116;117 18:19:21 INFO - PROCESS | 1669 | |1;audio-beat-detection;153;117;117;155;119;119;132;145;160;104 18:19:21 INFO - PROCESS | 1669 | |2;audio-dft;274;274;233;213;206;197;197;198;179;194 18:19:21 INFO - PROCESS | 1669 | |3;audio-fft;82;59;57;86;59;63;59;59;79;63 18:19:21 INFO - PROCESS | 1669 | |4;audio-oscillator;87;69;89;74;70;69;89;71;74;85 18:19:21 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;87;86;88;91;90;86;89;129;134;86 18:19:21 INFO - PROCESS | 1669 | |6;imaging-darkroom;95;94;94;94;97;149;153;96;95;122 18:19:21 INFO - PROCESS | 1669 | |7;imaging-desaturate;93;104;91;91;90;93;92;90;74;147 18:19:21 INFO - PROCESS | 1669 | |8;json-parse-financial;56;58;57;79;83;76;57;57;57;59 18:19:21 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;84;102;60;105;107;102;79;71;72;73 18:19:21 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;79;108;113;74;102;104;104;106;90;91 18:19:21 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;175;147;140;143;160;159;165;163;264;152 18:19:21 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;218;233;177;172;181;172;175;181;232;210 18:19:21 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;66;83;68;69;69;72;70;68;70;71 18:19:21 INFO - PROCESS | 1669 | __end_tp_report 18:19:21 INFO - PROCESS | 1669 | __start_cc_report 18:19:21 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,947 18:19:21 INFO - PROCESS | 1669 | __end_cc_report 18:19:21 INFO - PROCESS | 1669 | __startTimestamp1476321561295__endTimestamp 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | ------- Summary: start ------- 18:19:21 INFO - PROCESS | 1669 | Number of tests: 14 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:116.20 Median:117.00 stddev:3.05 (2.6%) stddev-sans-first:1.05 18:19:21 INFO - PROCESS | 1669 | Values: 108.0 118.0 118.0 117.0 117.0 116.0 119.0 116.0 116.0 117.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:138.50 stddev:19.71 (14.2%) stddev-sans-first:19.40 18:19:21 INFO - PROCESS | 1669 | Values: 153.0 117.0 117.0 155.0 119.0 119.0 132.0 145.0 160.0 104.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:216.50 Median:209.50 stddev:33.37 (15.9%) stddev-sans-first:28.17 18:19:21 INFO - PROCESS | 1669 | Values: 274.0 274.0 233.0 213.0 206.0 197.0 197.0 198.0 179.0 194.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:66.60 Median:63.00 stddev:11.14 (17.7%) stddev-sans-first:10.33 18:19:21 INFO - PROCESS | 1669 | Values: 82.0 59.0 57.0 86.0 59.0 63.0 59.0 59.0 79.0 63.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.50 stddev:8.68 (10.9%) stddev-sans-first:8.53 18:19:21 INFO - PROCESS | 1669 | Values: 87.0 69.0 89.0 74.0 70.0 69.0 89.0 71.0 74.0 85.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:87.50 stddev:18.51 (21.2%) stddev-sans-first:19.31 18:19:21 INFO - PROCESS | 1669 | Values: 87.0 86.0 88.0 91.0 90.0 86.0 89.0 129.0 134.0 86.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:94.50 stddev:23.77 (25.2%) stddev-sans-first:24.67 18:19:21 INFO - PROCESS | 1669 | Values: 95.0 94.0 94.0 94.0 97.0 149.0 153.0 96.0 95.0 122.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:91.00 stddev:19.16 (21.1%) stddev-sans-first:20.28 18:19:21 INFO - PROCESS | 1669 | Values: 93.0 104.0 91.0 91.0 90.0 93.0 92.0 90.0 74.0 147.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:58.50 stddev:10.81 (18.5%) stddev-sans-first:11.08 18:19:21 INFO - PROCESS | 1669 | Values: 56.0 58.0 57.0 79.0 83.0 76.0 57.0 57.0 57.0 59.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:85.50 Median:71.50 stddev:17.10 (23.9%) stddev-sans-first:18.12 18:19:21 INFO - PROCESS | 1669 | Values: 84.0 102.0 60.0 105.0 107.0 102.0 79.0 71.0 72.0 73.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:97.10 Median:93.50 stddev:12.99 (13.9%) stddev-sans-first:12.02 18:19:21 INFO - PROCESS | 1669 | Values: 79.0 108.0 113.0 74.0 102.0 104.0 104.0 106.0 90.0 91.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:166.80 Median:161.50 stddev:35.80 (22.2%) stddev-sans-first:37.85 18:19:21 INFO - PROCESS | 1669 | Values: 175.0 147.0 140.0 143.0 160.0 159.0 165.0 163.0 264.0 152.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.10 Median:195.50 stddev:25.26 (12.9%) stddev-sans-first:25.39 18:19:21 INFO - PROCESS | 1669 | Values: 218.0 233.0 177.0 172.0 181.0 172.0 175.0 181.0 232.0 210.0 18:19:21 INFO - PROCESS | 1669 | 18:19:21 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:70.00 stddev:4.67 (6.7%) stddev-sans-first:4.65 18:19:21 INFO - PROCESS | 1669 | Values: 66.0 83.0 68.0 69.0 69.0 72.0 70.0 68.0 70.0 71.0 18:19:21 INFO - PROCESS | 1669 | -------- Summary: end -------- 18:19:21 INFO - PROCESS | 1669 | 18:19:22 INFO - TEST-INFO | 1669: exit 0 18:19:22 INFO - TEST-OK | kraken | took 60358ms 18:19:22 INFO - SUITE-END | took 1176s 18:19:22 INFO - Completed test suite (00:19:36) 18:19:22 INFO - javascript benchmark 18:19:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7173.138013714934, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3250.9109448334807, 3278.639349671534, 3306.30567580808, 3349.012041447773, 3351.0784534253085, 4421.0, 4730.668853180278, 5199.610029247808, 5266.420693723692, 5441.102218134013, 5390.595705322106, 5562.137868630359, 5573.665580065189, 5657.745401456934, 5815.767369305223, 3998.9402808255836, 5101.285819985205, 5105.770240339194, 5135.2040433732645, 5244.895102097956, 4434.379186913835, 5220.321358223426, 5247.475252474741, 5263.0, 5285.99566082445, 5069.264956581312, 5178.629303682312, 5187.896242075138, 5193.584513238932, 5265.236540701596, 5126.436092029874, 5194.818181363672, 5200.609954253446, 5210.557102646242, 5235.55497782689, 5097.006083813658, 5138.229265610151, 5153.742312884341, 5158.509941555546, 5202.3497062867145, 4450.933236001462, 5456.91814622781, 5491.0, 5658.547316214693, 5721.856953576153, 5246.265522826808, 5654.208410822488, 5987.730552125165, 6029.065494848305, 6140.3552626974415, 6128.908066379008, 6132.936844521141, 6137.6010559313945, 6152.907706384408, 6170.81487555374, 5700.914486282709, 5712.685802280915, 5717.714114294268, 5719.256496655408, 5753.539716822645, 5995.190649262294, 6057.061155520813, 6072.726727297282, 6095.573309868267, 6105.175801266861, 5949.553783466257, 5986.910196347058, 6030.155778191057, 6057.909131362945, 6076.240469941257, 6069.15031895535, 6081.209442772411, 6092.421219984094, 6097.69511524422, 6107.4197951194565, 5883.352831188566, 5973.850653733692, 6016.398360163948, 6047.969760151259, 6081.969590152021, 5998.850028749316, 6102.69486525672, 6107.114468402056, 6108.3280839107665, 6127.571070025145, 4141.440905477722, 4777.570018698334, 4847.5394837490385, 4856.3201151838775, 4860.125177468019, 4234.640055595247, 4236.406903033578, 4252.043290259667, 4297.656187505055, 4309.525952145262, 5620.325560932714, 5624.718764061862, 5626.127950167731, 5640.661560306313, 5694.886102277931, 4801.847556586394, 5968.104784282305, 6164.198654174928, 6199.907001394983, 6205.596636218631, 5384.6769193848495, 5489.066858634042, 5500.80747173855, 5602.243697100921, 5603.859903502445, 5242.0, 5311.150215965427, 5324.0, 5351.143816989341, 5364.436734142859, 6218.502519798405, 6224.968875155595, 6239.875202495834, 6260.843478912972, 6370.872582548323, 5179.8446046618665, 5966.313873904556, 6055.1522786810765, 6163.876722465615, 6174.876502470015, 6120.203342909657, 6139.676133582418, 6174.166487524307, 6229.470495007975, 6248.4688801452385, 6059.303180134165, 6092.268927728701, 6099.176611157437, 6107.236595425572, 6119.020956646915, 5301.045811753921, 5583.637063590853, 5861.677607731615, 6142.201513803356, 6185.597936134137, 4176.331786914079, 4358.520562738159, 4654.813807447664, 5955.91066134017, 6056.939430605637, 5906.616069955439, 5942.732577033958, 5943.494802941798, 5975.880482390241, 5993.160957465871, 5695.345035320991, 5733.197352370588, 5747.3103227612955, 5876.206712093813, 5921.467067963904, 5927.347991720821, 5931.555133364928, 5943.375945525658, 5973.402659733992, 5977.671228082323, 5439.673619582838, 5528.19841123043, 5721.199032135588, 5787.247657804627, 5806.593538452268], "value": 5496.107214217121, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.1158846693254, 490.7227416509674, 499.1464595541624, 509.8317555206782, 534.7887584404159, 29987.250318742015, 39026.829195124155, 39354.21291574167, 39373.60626393735, 41321.38017929733, 40597.985050373754, 40828.795856020675, 40994.59005409945, 41087.0, 41133.7943310283, 9478.194353479963, 9630.085141911542, 9652.855207171899, 9670.468124253199, 9711.36876103051, 8348.290395316375, 9494.335396522247, 9521.238300935944, 9531.237500999903, 9617.90382096177, 9614.567344469515, 9640.662576809813, 9660.855087173699, 9665.98507156749, 9667.178289845371, 9530.475823829687, 9580.80838383232, 9621.278404119648, 9637.18083962864, 9645.03549645037, 8164.959175204086, 9475.0, 9595.28035397348, 9608.423494590346, 9612.66355677548, 38723.8063809682, 39008.21983560341, 39116.804415978026, 39129.217415651816, 39209.03954802248, 3259.103746469716, 3273.5580696605894, 3322.96677033229, 3351.145457908242, 3424.332255210235, 28440.573391399146, 29331.853340733374, 29445.70554294451, 29667.0, 30332.0, 3208.2139875730595, 3208.911219792573, 3218.7585931055264, 3244.6917542833394, 3327.3012667339885, 392150.0, 393314.0, 394850.0, 396175.0, 396341.0, 55370.0, 55404.44595554074, 55655.443445565834, 55791.721041394536, 56048.43951560514, 51027.0, 58903.0, 58987.705061474415, 59216.70391648014, 59398.0, 15849.920750396404, 15975.760363594556, 16008.839911600735, 16041.0, 16073.357065717475, 12245.510179592717, 12263.570775022832, 12309.26144431337, 12324.50701971929, 12332.630021099381, 14568.781468277984, 16308.0, 18093.914365138135, 18175.909120454577, 18203.817961820478, 7484.401247900155, 7513.323800857949, 7532.472726909064, 7563.432742544332, 7598.088229412396, 838.7525679924437, 949.6506002508495, 988.1551273660916, 1034.9482525873675, 1048.648702684594, 24774.876125619616, 25270.241892743248, 27465.725342746715, 29846.25384365408, 30113.849430752707, 869.9869501957476, 986.4377304936118, 1030.845373194027, 1114.0530549033258, 1116.2855772305732, 14890.404383824525, 15257.771133433007, 15388.0, 15392.461263855712, 15559.45541906028, 699268.0, 702285.0, 704981.0, 705558.0, 709804.0, 52817.0, 54619.180712289344, 55079.724601376736, 55640.443595564335, 55892.44107558954, 1300.9414576344088, 1365.7200273943818, 1367.3095086981011, 1368.056041331498, 1370.5751217122524, 26938.730612693624, 27986.72013279841, 28884.0, 29183.832646693896, 29224.0, 46638.76680616575, 46971.0, 47018.29472557983, 47187.528124718316, 47247.29129063136, 521093.0, 521248.0, 521638.0, 522855.0, 524261.0, 22871.0, 24925.0, 25424.364390890376, 25786.742132579184, 25832.354191145372, 36177.63822361848, 39048.80475597717, 45648.54351456443, 46786.29820552626, 46833.53166468428, 30875.536866946564, 34401.82799086089, 37191.814040929625, 37803.05492362713, 38846.4173037399, 37534.62465375311, 38664.0, 38691.22617547577, 38754.2249155021, 39025.0, 36788.44817327688, 36987.81506092452, 37024.81487592545, 37600.0, 37906.62093379141, 32739.672603273662, 34137.82931085329, 34276.828615856764, 34452.31095378028, 34554.82722586371, 22407.663885042064, 25299.114530991326, 25318.873405632854, 25740.841662124876, 25944.351391215372], "value": 19242.377530686346, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.49838478552846, 369.5343866727922, 373.0822177443489, 378.22086501806297, 396.179907591286, 19591.608167836635, 19728.506787330323, 20123.094460749264, 20466.283680071218, 20618.278360257395, 11556.017738492217, 11751.7649647007, 18695.252189912386, 19523.023848807574, 20163.798362016376, 5081.872953176173, 5339.759710813003, 5340.092184328654, 5355.7857685692725, 5365.195220716884, 4886.218205087187, 5149.485051494858, 5869.8826023479505, 5877.14781356703, 5881.264841894763, 4985.825496107637, 5756.654600723949, 5831.825045248649, 5849.093390524455, 5870.647761134345, 5292.338457692788, 5301.602379821509, 5336.4129945705945, 5339.305890234284, 5339.41266460691, 5034.446210916796, 5188.806574487889, 5205.609579281569, 5212.061828870802, 5228.973855130738, 20427.795722042887, 23263.25525585588, 23634.29097127089, 24976.250712478653, 25161.0, 2043.3563427520282, 2072.367927782026, 2119.5760847830343, 2206.4042708468783, 2371.9407014824596, 9866.358686685342, 10237.846432303522, 10401.739956501073, 11804.232724872856, 14806.62983425423, 1839.6688596052704, 1861.1438738180452, 1870.7193920911836, 1909.767936367568, 1928.5757133430764, 108958.0, 119543.0, 123417.0, 124518.37740811329, 124912.37543812314, 9292.258783658837, 9624.941256461783, 10016.899831001743, 10020.899791002143, 10125.443100629536, 12244.387780610934, 12277.754444911052, 12287.201331913397, 12391.62825115248, 12439.626811195678, 7560.508566943131, 7762.495437796638, 7791.259830316111, 7890.763277101696, 7903.683852645945, 7883.802904927308, 7888.171741967126, 7894.447388682852, 8073.39449541275, 8094.311983481352, 3358.429067058606, 3490.616032236452, 3633.109888077438, 3848.0572259795968, 3873.6320049595242, 1523.7333466643292, 1651.5788473939308, 1654.859336956372, 1931.4495368819819, 1954.5797653504565, 896.7220161749879, 961.778790878088, 997.0, 998.7453199434098, 1015.2385710716962, 19176.424707258804, 21649.67525487119, 21689.0, 21689.0, 21885.562288754136, 775.5811861594789, 803.1687203744096, 854.9444286121383, 959.4579062829479, 994.4033579852031, 10734.78530429387, 11350.772984540263, 11386.259893106922, 11409.0, 11436.198131457846, 571156.1442192845, 578223.0, 578856.1057194687, 579456.0, 583444.0, 102075.4896225529, 122618.0, 123658.0, 124461.75538244502, 125694.0, 968.2109081098882, 1018.4703953944056, 1027.5941003303803, 1028.8971102889652, 1029.7580068683853, 18989.90505047466, 19915.10382032783, 19916.0, 19953.401397958085, 20057.0, 53249.0, 53556.46443535515, 54836.0, 55634.16548751691, 55693.72153139208, 76964.615176926, 77028.22971770435, 77699.0, 78394.82407763774, 79185.0, 7892.763217103496, 7999.800004999922, 8233.752987410388, 8253.380996425172, 8259.628316725642, 10865.293755905841, 12702.745945080862, 12742.745145097231, 12770.169938953939, 12922.095453318178, 11126.721831954266, 11264.0, 11402.600908968147, 11468.426578670866, 11792.0, 10892.18308626872, 10994.175436842428, 11129.387883666475, 11177.88822111801, 11219.0, 11379.260348077349, 11421.657350279505, 11592.942035289769, 11643.767124657628, 11824.349660768741, 7945.443818932619, 7952.403569732409, 8321.00147982246, 8352.707655232038, 8379.371547134111, 10404.323718958243, 11020.50407731638, 11123.27698699582, 11295.717607059889, 11555.768884622428], "value": 9235.077721113574, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.5407223760635, 1522.101959843692, 1669.5492217101382, 1774.2636805725624, 1790.9015004174764, 7969.641366138523, 8218.342532597393, 8437.66249350026, 9063.456192628439, 9075.319351048673, 7309.6345182740915, 8698.999615044271, 8742.562871856398, 8853.734387968356, 8934.86597701035, 2348.9060437582475, 2373.4896997145697, 2373.95252094958, 2375.334906226251, 2381.583222935988, 2337.4857531343077, 2368.810495160392, 2385.6063749481314, 2387.689600351952, 2393.5930891748358, 2333.88330583471, 2364.290712786166, 2367.5264947010583, 2385.9263331500806, 2388.22382725614, 2323.2101085630884, 2327.034280773477, 2341.1922886604093, 2352.9882350588223, 2366.219147681261, 2331.603627383349, 2347.319277409549, 2351.988240058806, 2352.8941197646145, 2355.6819829322994, 8961.32790040743, 8965.058668839809, 8966.775830604221, 8991.505467199302, 8992.505412202327, 4741.407324084489, 5192.688438693691, 5335.519803217729, 5385.5960802939935, 5427.213054107172, 3273.312604353089, 3392.8982130536124, 3613.9096522586883, 3635.309291234657, 3727.8136093195503, 1043.8048434542482, 1044.5456226541478, 1061.75048863517, 1062.1555863088863, 1068.2735739697002, 10350.223733220038, 11661.242019268806, 11689.76620467586, 11698.356590387526, 11719.589814356541, 4083.2037752637953, 4571.451425828922, 4634.443866736014, 4826.8793280168275, 4830.130576496264, 9936.801263974825, 9982.101610855058, 10011.249156313306, 10018.499075046218, 10019.599216031424, 5374.811881584125, 5393.6763794172475, 5409.26974858397, 5416.91874621881, 5424.186372044225, 3866.3620502616754, 4418.094290670405, 4589.128065667513, 4859.465458799529, 4944.505549445099, 3032.120685001325, 3636.8545258189374, 3857.40210267409, 4024.4164596133396, 4072.8392408164123, 1127.720037757135, 1269.847618285793, 1290.270996886756, 1290.6386211860695, 1298.104308027458, 817.6320655704957, 893.647009431284, 912.2063804490136, 933.2254228989922, 934.3926447808842, 5677.290338707661, 5771.480566749013, 5995.250593675791, 5997.670128142907, 6092.086187071975, 440.8126546217845, 446.5802145982766, 446.99203296566037, 955.7849483866214, 968.5544649461186, 3439.7076248519156, 3848.230353929225, 3977.343738283209, 4049.5140583129623, 4081.0409553754844, 9921.908590055089, 9980.850287245697, 10395.428251446243, 10786.352818830896, 10810.729731756768, 10529.315594486334, 10598.364098154136, 10667.893321066846, 10687.78624427507, 10752.193585480964, 8129.268365847102, 8256.298214651702, 8288.129746376664, 8302.54336011513, 8307.335413166933, 5776.075827867521, 5846.0, 5940.495057920125, 5956.136360227656, 5956.404359564182, 9532.380395274286, 9999.3000489965, 10018.64934727281, 10070.597176112873, 10078.193744500275, 10159.949200253952, 10196.286259961731, 10291.691249262534, 10304.227182961458, 10323.070923616911, 6496.123023391781, 6718.93281067183, 6866.244713081657, 7045.330693584101, 7139.32176443237, 8168.428210025241, 8436.409451338348, 8527.445716028686, 8532.23209911111, 8535.914640853513, 7379.926200737924, 7527.209642987407, 7543.622818859145, 7594.278543538355, 7639.656215470206, 7289.671964761493, 7383.8154046149275, 7438.962805185939, 7515.060617422822, 7550.735724249625, 6887.414569761426, 6996.895046574204, 7047.436205103682, 7051.682674279772, 7051.753188638373, 4154.958450415457, 4931.211006239128, 5050.242463630485, 5115.235243840869, 5154.664946778447, 4953.108440480748, 5065.670731402447, 5129.076766181974, 5227.817026404057, 5237.135872580981], "value": 4520.407326994952, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.81131104370814, 313.474929493099, 313.7552708887067, 318.1838584031959, 320.8139279218054, 4604.470485894123, 5426.538744206742, 5462.945370546293, 5494.423085576017, 5568.832935011951, 5329.360476742796, 5345.209354866124, 5380.273663055486, 5384.865378365543, 5419.783208671658, 3210.6628803975536, 3632.600413954463, 3766.6986641068784, 4025.154717509312, 4084.5098588169312, 3638.0, 4103.569125241851, 4133.503979522447, 4152.792360381969, 4324.351347297915, 4106.589341065885, 4112.0, 4146.3780432935, 4146.688998325122, 4152.563980782004, 3510.069461796015, 3640.468745317038, 3777.3389656810223, 4042.2618274142105, 4173.812178451962, 3473.322702073097, 3487.825608719579, 3489.354469423165, 3501.457274122515, 3515.402381595135, 4613.723176609415, 4827.62066553414, 5223.843284701465, 5251.474852514756, 5294.385212510183, 4528.773561321921, 4617.291602107208, 4690.319903613991, 4692.554207350297, 4706.3411122442885, 5675.744591493392, 5697.316322041382, 5699.772009119631, 5710.71446427677, 5715.599908006441, 3872.8838134856, 3875.976738144252, 3878.0803502266276, 3878.4957955465893, 3915.138669492707, 4809.95190048102, 4989.800407983676, 5059.3422855028975, 5193.558547523465, 5206.323177986876, 3522.5949015862993, 3888.475055867478, 3888.6111388860886, 3891.64975152232, 3893.3381325174787, 3389.6440873708398, 3980.4825372701944, 3990.4812374391145, 3996.1208534122447, 4004.3392840181045, 4614.515475875052, 4641.234196357564, 4661.790219440133, 4682.648801339845, 4691.81232750693, 4878.390201224847, 5029.396472423333, 5220.9738951305, 5688.236646639555, 5766.279215098113, 1331.8002299654936, 1356.409961666678, 1358.2665360705307, 1360.394624392136, 1365.7609918264263, 703.9225685174627, 758.1319389299222, 774.8295375017487, 809.7449303469389, 814.5764202614607, 5072.930876819545, 5168.2764412982215, 5316.7075810830775, 5335.653182543122, 5350.357957045179, 5186.377634683787, 5622.718864056781, 5904.9704751475965, 5924.585279030426, 5968.015277479254, 4366.432363792686, 5071.974640126849, 5130.666506677054, 5296.0, 5475.835724928259, 2468.2101727447402, 3047.4362242985117, 3061.663217046123, 3076.8153910765427, 3087.9073627791204, 6014.278286605635, 6044.486218671462, 6045.455908968126, 6095.988323853117, 6115.877682446326, 5435.8856434430845, 5459.972700136554, 5636.098224284095, 5767.423257674199, 6039.66781826995, 4772.642051846056, 5439.564834813205, 5657.462541058675, 6054.091886217103, 6091.360407157274, 5163.540427913232, 5616.8876622466505, 5805.287440205234, 5848.561357898089, 5894.970525147347, 4425.822967081281, 5046.520580544842, 5521.840413513047, 5739.454751798572, 5763.682997435181, 5095.490450954875, 5824.679642619697, 6067.332593414632, 6094.506845822802, 6111.663858487826, 3235.53084802707, 3760.777747232106, 3846.4614953907035, 3869.7871617061332, 3937.4684417603257, 5011.9474910269255, 5045.066662667418, 5317.042932272228, 5528.530074943675, 5678.460546248265, 4605.40129783126, 5006.724630145377, 5558.027345214652, 5596.8320950369925, 5633.521150702072, 5348.973255133855, 5541.612087153861, 5543.667379957215, 5545.975718862666, 5615.775368985358, 4423.579759922803, 5432.29380180574, 5452.509274165185, 5456.372517160577, 5480.753366098615, 4568.954310456986, 4788.497207793132, 5310.309659744208, 5334.0, 5623.831285061455, 5327.760250788646, 5470.632341914521, 5472.890542189054, 5479.260299859628, 5483.396826349019], "value": 4081.5530099088865, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.4039080138259, 699.5103427600677, 721.592300350302, 747.8653842308385, 776.4060493722307, 14964.401423943043, 15393.846061539381, 15959.361625534979, 15970.121643309614, 16055.438059667915, 14448.783268250958, 14454.638634034154, 14604.634884127901, 14606.853931460682, 14674.0, 689.4036658290593, 843.1105184030845, 867.3278209387712, 871.1245198575455, 871.755908345661, 705.9435245180399, 845.4251109245711, 865.16079402979, 872.6640243506264, 877.692807517367, 862.7023676831515, 863.365426411587, 913.821804748071, 915.9587818548148, 918.8437965545874, 891.5096696816757, 908.8682141089505, 911.9407238529475, 913.196387179278, 915.922146617535, 650.8437974886041, 681.2029924987727, 681.3186813186813, 682.1302838880467, 682.3381320119477, 11267.774644507146, 12174.26954382731, 14137.081089729134, 14398.76027893726, 14399.992000559965, 10802.243842930999, 12176.330301833395, 14413.351399187062, 14545.490907818175, 14612.050216735877, 11485.94257028718, 11854.703632409173, 11873.762524749543, 11889.524419023228, 12064.406152677217, 3766.1714422826935, 3774.3772277574167, 3886.6890648748317, 3912.902177445558, 3959.78221197834, 87605.0, 112853.0, 114995.42502287435, 115091.0, 118679.40660296644, 11055.115590752881, 11057.612983545698, 11114.444277786079, 11118.722031949266, 11493.827592586118, 23042.0, 23477.1782987598, 23477.765222347556, 23508.882455587613, 23542.764572354055, 6259.6870156492, 7784.688612455551, 7839.294463498312, 7869.291763741291, 7951.324137448381, 4277.572242775697, 4355.303151495746, 4382.233109205877, 4447.510773814878, 4495.145922274757, 3565.0, 3668.9633103669157, 3677.7057835373107, 3690.317291301118, 3701.2227432239533, 660.4485254812286, 662.3442791636245, 669.0733334331888, 706.2937062937064, 718.7340683946973, 586.5219845825638, 644.1818890009662, 662.7400564076572, 662.9801255282235, 683.2893790457967, 7344.889826652605, 9329.300302477206, 9440.339176257596, 9807.411555306704, 10062.490626405952, 550.6179489481351, 565.6973519167226, 621.9458018658331, 672.1363048482676, 699.3286445012844, 4258.339957306623, 4266.847951053198, 4378.890527736832, 4692.328043648591, 4783.6651434399255, 87353.78216792295, 92729.0, 93231.53384233035, 97446.0, 143708.5629143695, 100261.0, 131993.34003329923, 132807.0, 134398.0, 136955.0, 75340.11649708691, 106132.0, 108151.0, 116628.0, 116759.0, 7720.498167619086, 8800.91199088001, 8997.865032024394, 9046.773830654287, 9205.447673139633, 58693.0, 62800.685996571556, 63051.80326557518, 63456.0, 64931.67534162299, 94112.0, 95577.0, 96613.0, 97498.51250743702, 97765.51117244369, 11629.709257268636, 11805.0, 12152.392380381123, 12175.086868484723, 12299.323537205539, 13125.934370328087, 13233.933830330787, 13323.467061317438, 13397.330133493091, 13874.722505550035, 12245.571405000783, 12276.780158015104, 12321.815172772236, 12407.0, 12733.426995785027, 12410.193337433038, 12434.253944763343, 12598.496060157491, 12763.170393924365, 12776.233425994471, 10531.473426328806, 10853.294535855144, 11073.05879000294, 11404.657860264206, 12602.621921342374, 10202.948985255027, 10222.335548189641, 10351.482425878525, 10449.843252351373, 10834.945825270823, 8973.37186396972, 9780.559874805793, 10266.486675666336, 10457.68626941193, 10711.410872402093], "value": 7558.79137554723, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 118.0, 117.0, 117.0, 116.0, 119.0, 116.0, 116.0, 117.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 117.0, 155.0, 119.0, 119.0, 132.0, 145.0, 160.0, 104.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [274.0, 274.0, 233.0, 213.0, 206.0, 197.0, 197.0, 198.0, 179.0, 194.0], "value": 216.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 57.0, 86.0, 59.0, 63.0, 59.0, 59.0, 79.0, 63.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 89.0, 74.0, 70.0, 69.0, 89.0, 71.0, 74.0, 85.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 91.0, 90.0, 86.0, 89.0, 129.0, 134.0, 86.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 97.0, 149.0, 153.0, 96.0, 95.0, 122.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 104.0, 91.0, 91.0, 90.0, 93.0, 92.0, 90.0, 74.0, 147.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 79.0, 83.0, 76.0, 57.0, 57.0, 57.0, 59.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 102.0, 60.0, 105.0, 107.0, 102.0, 79.0, 71.0, 72.0, 73.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 108.0, 113.0, 74.0, 102.0, 104.0, 104.0, 106.0, 90.0, 91.0], "value": 97.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 147.0, 140.0, 143.0, 160.0, 159.0, 165.0, 163.0, 264.0, 152.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 233.0, 177.0, 172.0, 181.0, 172.0, 175.0, 181.0, 232.0, 210.0], "value": 195.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 83.0, 68.0, 69.0, 69.0, 72.0, 70.0, 68.0, 70.0, 71.0], "value": 70.6, "unit": "score"}]}]} 18:19:22 INFO - Return code: 0 18:19:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:19:22 ERROR - # TBPL SUCCESS # 18:19:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:19:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:19:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:19:22 INFO - 2016-10-12 18:19:22.633 screenresolution[1674:11395] starting screenresolution argv=screenresolution get 18:19:22 INFO - 2016-10-12 18:19:22.636 screenresolution[1674:11395] Display 0: 1600x1200x32@60 18:19:22 INFO - 2016-10-12 18:19:22.642 screenresolution[1675:11398] starting screenresolution argv=screenresolution list 18:19:22 INFO - Available Modes on Display 0 18:19:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:19:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:19:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:19:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:19:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:19:22 INFO - Intel Iris: 18:19:22 INFO - Chipset Model: Intel Iris 18:19:22 INFO - Type: GPU 18:19:22 INFO - Bus: Built-In 18:19:22 INFO - VRAM (Dynamic, Max): 1536 MB 18:19:22 INFO - Vendor: Intel (0x8086) 18:19:22 INFO - Device ID: 0x0a2e 18:19:22 INFO - Revision ID: 0x0009 18:19:22 INFO - Displays: 18:19:22 INFO - Display: 18:19:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:19:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:19:22 INFO - Display Serial Number: 1600x1200 60 18:19:22 INFO - Main Display: Yes 18:19:22 INFO - Mirror: Off 18:19:22 INFO - Online: Yes 18:19:22 INFO - Rotation: Supported 18:19:22 INFO - Return code: 0 18:19:22 INFO - Running post-action listener: _resource_record_post_action 18:19:22 INFO - [mozharness: 2016-10-13 01:19:22.875468Z] Finished run-tests step (success) 18:19:22 INFO - Running post-run listener: _resource_record_post_run 18:19:22 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 40174592; Write bytes: 713896448; Read time: 367; Write time: 2196 18:19:22 INFO - TinderboxPrint: CPU usage
54.8% 18:19:22 INFO - TinderboxPrint: I/O read bytes / time
40,174,592 / 367 18:19:22 INFO - TinderboxPrint: I/O write bytes / time
713,896,448 / 2,196 18:19:22 INFO - TinderboxPrint: CPU idle
2,191.0 (45.2%) 18:19:22 INFO - TinderboxPrint: CPU system
987.8 (20.4%) 18:19:22 INFO - TinderboxPrint: CPU user
1,666.9 (34.4%) 18:19:22 INFO - TinderboxPrint: Swap in / out
451,428,352 / 0 18:19:22 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401744384; Write bytes: 396584960; Read time: 29683; Write time: 776 18:19:22 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 38106112; Write bytes: 302254592; Read time: 345; Write time: 1377 18:19:23 INFO - Running post-run listener: _upload_blobber_files 18:19:23 INFO - Blob upload gear active. 18:19:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:19:23 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 18:19:23 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:19:23 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:19:23 INFO - (blobuploader) - INFO - Open directory for files ... 18:19:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:19:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:19:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:19:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:19:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:19:24 INFO - (blobuploader) - INFO - Done attempting. 18:19:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:19:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:19:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:19:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:19:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:19:25 INFO - (blobuploader) - INFO - Done attempting. 18:19:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:19:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:19:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:19:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:19:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:19:29 INFO - (blobuploader) - INFO - Done attempting. 18:19:29 INFO - (blobuploader) - INFO - Iteration through files over. 18:19:29 INFO - Return code: 0 18:19:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:19:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:19:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/272ec1546f596c7b74b35c9714521cc2cefb2347726337e5435bf77fdff2ee6d907955824c94f38deb3f00ee9abb898fe9b481c9a78b0ea34bb48132952e0dec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/680acd30070177ecfad3f49eff961252a8863f5a5ec7b1481501e9cb043c24bbb3647e50505c9889882ee8163cac9534683229f571793b6147537c5994b79a82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53cb6ede5dc54df03efbe9abbc5e3180150dc5cc51ecd02c4afdf019154d85ce2884c48de60b5a86353ac67094aa6c740fd23c14e5b4a0d7c50a84440bc03a3d"} 18:19:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:19:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:19:29 INFO - Contents: 18:19:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/272ec1546f596c7b74b35c9714521cc2cefb2347726337e5435bf77fdff2ee6d907955824c94f38deb3f00ee9abb898fe9b481c9a78b0ea34bb48132952e0dec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/680acd30070177ecfad3f49eff961252a8863f5a5ec7b1481501e9cb043c24bbb3647e50505c9889882ee8163cac9534683229f571793b6147537c5994b79a82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53cb6ede5dc54df03efbe9abbc5e3180150dc5cc51ecd02c4afdf019154d85ce2884c48de60b5a86353ac67094aa6c740fd23c14e5b4a0d7c50a84440bc03a3d"} 18:19:29 INFO - Running post-run listener: copy_logs_to_upload_dir 18:19:29 INFO - Copying logs to upload dir... 18:19:29 INFO - mkdir: /builds/slave/test/build/upload/logs 18:19:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.518454 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-12 18:19:29.467175) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 18:19:29.470949) ========= 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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/272ec1546f596c7b74b35c9714521cc2cefb2347726337e5435bf77fdff2ee6d907955824c94f38deb3f00ee9abb898fe9b481c9a78b0ea34bb48132952e0dec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/680acd30070177ecfad3f49eff961252a8863f5a5ec7b1481501e9cb043c24bbb3647e50505c9889882ee8163cac9534683229f571793b6147537c5994b79a82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53cb6ede5dc54df03efbe9abbc5e3180150dc5cc51ecd02c4afdf019154d85ce2884c48de60b5a86353ac67094aa6c740fd23c14e5b4a0d7c50a84440bc03a3d"} build_url:https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011026 build_url: 'https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/272ec1546f596c7b74b35c9714521cc2cefb2347726337e5435bf77fdff2ee6d907955824c94f38deb3f00ee9abb898fe9b481c9a78b0ea34bb48132952e0dec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/680acd30070177ecfad3f49eff961252a8863f5a5ec7b1481501e9cb043c24bbb3647e50505c9889882ee8163cac9534683229f571793b6147537c5994b79a82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53cb6ede5dc54df03efbe9abbc5e3180150dc5cc51ecd02c4afdf019154d85ce2884c48de60b5a86353ac67094aa6c740fd23c14e5b4a0d7c50a84440bc03a3d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 18:19:29.505056) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:19:29.505374) ========= 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.ahjY9j2vwh/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.qKeFBMiHVb/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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.005148 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 18:19:29.554699) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 18:19:29.555003) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 18:19:29.555315) ========= ========= Total master_lag: 0.17 =========