builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0313 starttime: 1476316747.24 results: success (0) buildid: 20161012144547 builduid: bfe8229c097446c2a44a972f6a05e5bd revision: d68def1644c1849141ceaa31bc1b811becfbb8da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:07.236891) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:07.237361) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:07.237639) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:07.264117) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:07.264470) ========= 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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.12M=0.001s 2016-10-12 16:59:07 (8.12 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105770 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:07.391734) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:07.395068) ========= 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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.020759 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:07.443349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 16:59:07.443665) ========= 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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:07,524 truncating revision to first 12 chars 2016-10-12 16:59:07,524 Setting DEBUG logging. 2016-10-12 16:59:07,524 attempt 1/10 2016-10-12 16:59:07,524 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 16:59:08,695 unpacking tar archive at: fx-team-d68def1644c1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.444254 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 16:59:08.912419) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:08.912743) ========= script_repo_revision: d68def1644c1849141ceaa31bc1b811becfbb8da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:08.917168) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:08.917446) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 16:59:08.939680) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-12 16:59:08.940067) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:09 INFO - MultiFileLogger online at 20161012 16:59:09 in /builds/slave/test 16:59:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:59:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:59:09 INFO - {'append_to_log': False, 16:59:09 INFO - 'base_work_dir': '/builds/slave/test', 16:59:09 INFO - 'blob_upload_branch': 'Fx-Team', 16:59:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:59:09 INFO - 'branch': 'Fx-Team', 16:59:09 INFO - 'buildbot_json_path': 'buildprops.json', 16:59:09 INFO - 'config_files': ('talos/mac_config.py',), 16:59:09 INFO - 'default_actions': ('clobber', 16:59:09 INFO - 'read-buildbot-config', 16:59:09 INFO - 'download-and-extract', 16:59:09 INFO - 'populate-webroot', 16:59:09 INFO - 'create-virtualenv', 16:59:09 INFO - 'install', 16:59:09 INFO - 'run-tests'), 16:59:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:59:09 INFO - 'download_minidump_stackwalk': True, 16:59:09 INFO - 'download_symbols': 'ondemand', 16:59:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:59:09 INFO - 'tooltool.py': '/tools/tooltool.py', 16:59:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:59:09 INFO - '/tools/misc-python/virtualenv.py')}, 16:59:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:59:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:59:09 INFO - 'installer_path': 'installer.exe', 16:59:09 INFO - 'log_level': 'info', 16:59:09 INFO - 'log_name': 'talos', 16:59:09 INFO - 'log_to_console': True, 16:59:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:59:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:59:09 INFO - 'opt_config_files': (), 16:59:09 INFO - 'pip_index': False, 16:59:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:59:09 INFO - 'cmd': ('bash', 16:59:09 INFO - '-c', 16:59:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:59:09 INFO - 'enabled': True, 16:59:09 INFO - 'halt_on_failure': False, 16:59:09 INFO - 'name': 'check_screen_resolution'},), 16:59:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:59:09 INFO - 'cmd': ('bash', 16:59:09 INFO - '-c', 16:59:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:59:09 INFO - 'enabled': True, 16:59:09 INFO - 'halt_on_failure': False, 16:59:09 INFO - 'name': 'check_screen_resolution'},), 16:59:09 INFO - 'run_cmd_checks_enabled': True, 16:59:09 INFO - 'sps_profile': False, 16:59:09 INFO - 'sps_profile_interval': 0, 16:59:09 INFO - 'suite': 'dromaeojs', 16:59:09 INFO - 'system_bits': '32', 16:59:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:59:09 INFO - 'title': 't-yosemite-r7-0313', 16:59:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:59:09 INFO - 'use_talos_json': True, 16:59:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:59:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:59:09 INFO - 'work_dir': 'build'} 16:59:09 INFO - [mozharness: 2016-10-12 23:59:09.136578Z] Running clobber step. 16:59:09 INFO - Running pre-action listener: _resource_record_pre_action 16:59:09 INFO - Running main action method: clobber 16:59:09 INFO - rmtree: /builds/slave/test/build 16:59:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:59:10 INFO - Running post-action listener: _resource_record_post_action 16:59:10 INFO - [mozharness: 2016-10-12 23:59:10.778259Z] Finished clobber step (success) 16:59:10 INFO - [mozharness: 2016-10-12 23:59:10.778382Z] Running read-buildbot-config step. 16:59:10 INFO - Running pre-action listener: _resource_record_pre_action 16:59:10 INFO - Running main action method: read_buildbot_config 16:59:10 INFO - Using buildbot properties: 16:59:10 INFO - { 16:59:10 INFO - "project": "", 16:59:10 INFO - "product": "firefox", 16:59:10 INFO - "script_repo_revision": "production", 16:59:10 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:59:10 INFO - "repository": "", 16:59:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:59:10 INFO - "buildid": "20161012144547", 16:59:10 INFO - "pgo_build": "False", 16:59:10 INFO - "basedir": "/builds/slave/test", 16:59:10 INFO - "buildnumber": 183, 16:59:10 INFO - "slavename": "t-yosemite-r7-0313", 16:59:10 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:59:10 INFO - "platform": "yosemite_r7", 16:59:10 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:59:10 INFO - "branch": "fx-team", 16:59:10 INFO - "repo_path": "integration/fx-team", 16:59:10 INFO - "revision": "d68def1644c1849141ceaa31bc1b811becfbb8da", 16:59:10 INFO - "stage_platform": "macosx64", 16:59:10 INFO - "builduid": "bfe8229c097446c2a44a972f6a05e5bd", 16:59:10 INFO - "slavebuilddir": "test" 16:59:10 INFO - } 16:59:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:59:10 INFO - Running post-action listener: _resource_record_post_action 16:59:10 INFO - [mozharness: 2016-10-12 23:59:10.781739Z] Finished read-buildbot-config step (success) 16:59:10 INFO - [mozharness: 2016-10-12 23:59:10.781874Z] Running download-and-extract step. 16:59:10 INFO - Running pre-action listener: _resource_record_pre_action 16:59:10 INFO - Running main action method: download_and_extract 16:59:10 INFO - mkdir: /builds/slave/test/build/tests 16: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')]} 16:59:10 INFO - trying https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:59:10 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 16:59:10 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 16:59:12 INFO - Downloaded 1199 bytes. 16:59:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:59:12 INFO - Using the following test package requirements: 16:59:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:59:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:59:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:59:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:59:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:59:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:59:12 INFO - u'jsshell-mac.zip'], 16:59:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:59:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:59:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:59:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:59:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:59:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:59:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:59:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:59:12 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:59:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:59:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:59:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:59:12 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 16:59:12 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 16:59:12 INFO - Fetch https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:59:12 INFO - Http code: 200 16:59:12 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 16:59:12 INFO - x-amz-cf-id: Xa-JOJzdWTlOsh-yp52LZsx39D9wkVwRNnLyO-Ot_0Vz7tk9-ZuMmQ== 16:59:12 INFO - x-amz-version-id: 8H1Yo9A4aP6YKnA3Zz6FLWn6YFQdVnDp 16:59:13 INFO - Expected file size: 16511123 16:59:13 INFO - Obtained file size: 16511123 16:59:19 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 16:59:19 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 16:59:19 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 16:59:19 INFO - Fetch https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:59:20 INFO - Http code: 200 16:59:20 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 16:59:20 INFO - x-amz-cf-id: FvzUEzhKF2_nPmzkBvLfy0Q717vB26psvOhT1ihIDRMxPCNEsKLrKg== 16:59:20 INFO - x-amz-version-id: 8H1Yo9A4aP6YKnA3Zz6FLWn6YFQdVnDp 16:59:20 INFO - Expected file size: 16511123 16:59:20 INFO - Obtained file size: 16511123 16:59: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.talos.tests.zip 16:59: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.talos.tests.zip'}, attempt #1 16:59:26 INFO - Fetch https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:59:27 INFO - Http code: 200 16:59:27 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 16:59:27 INFO - x-amz-cf-id: 2v2mKC181_IrW_y6I8P17GFvjA1SAMApgkCCd34AkRjtM4walv4rcw== 16:59:27 INFO - x-amz-version-id: UCJ8bGSsXzvAnwbq.ZZdq3fva50xxV0C 16:59:27 INFO - Expected file size: 14409006 16:59:27 INFO - Obtained file size: 14409006 16:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:28 INFO - trying https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:59:28 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 16:59:28 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 16:59:31 INFO - Downloaded 111694480 bytes. 16:59:31 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 16:59:31 INFO - mkdir: /builds/slave/test/properties 16:59:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:59:31 INFO - Writing to file /builds/slave/test/properties/build_url 16:59:31 INFO - Contents: 16:59:31 INFO - build_url:https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:59:31 INFO - Running post-action listener: _resource_record_post_action 16:59:31 INFO - Running post-action listener: set_extra_try_arguments 16:59:31 INFO - [mozharness: 2016-10-12 23:59:31.981124Z] Finished download-and-extract step (success) 16:59:31 INFO - [mozharness: 2016-10-12 23:59:31.981245Z] Running populate-webroot step. 16:59:31 INFO - Running pre-action listener: _resource_record_pre_action 16:59:31 INFO - Running main action method: populate_webroot 16:59:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:59:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:59:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:59:31 INFO - '--tppagecycles', 16:59:31 INFO - '7'], 16:59:31 INFO - 'tests': ['tsvgm']}}, 16:59:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:59:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:59:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:59:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:59:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:59:31 INFO - 'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:59:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:59:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:59:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:59:31 INFO - 'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['damp', 'tps']}, 16:59:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:59:31 INFO - 'tests': ['damp', 'tps']}, 16:59:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['dromaeo_dom']}, 16:59:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:59:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['basic_compositor_video']}, 16:59:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:59:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['a11yr', 16:59:31 INFO - 'ts_paint', 16:59:31 INFO - 'tpaint', 16:59:31 INFO - 'sessionrestore', 16:59:31 INFO - 'sessionrestore_no_auto_restore', 16:59:31 INFO - 'tabpaint']}, 16:59:31 INFO - 'other-e10s': {'tests': ['a11yr', 16:59:31 INFO - 'ts_paint', 16:59:31 INFO - 'tpaint', 16:59:31 INFO - 'sessionrestore', 16:59:31 INFO - 'sessionrestore_no_auto_restore', 16:59:31 INFO - 'tabpaint']}, 16:59:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:59:31 INFO - 'ts_paint', 16:59:31 INFO - 'tpaint', 16:59:31 INFO - 'sessionrestore', 16:59:31 INFO - 'sessionrestore_no_auto_restore', 16:59:31 INFO - 'tabpaint']}, 16:59:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:59:31 INFO - 'ts_paint', 16:59:31 INFO - 'tpaint', 16:59:31 INFO - 'sessionrestore', 16:59:31 INFO - 'sessionrestore_no_auto_restore', 16:59:31 INFO - 'tabpaint']}, 16:59:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['a11yr', 16:59:31 INFO - 'ts_paint', 16:59:31 INFO - 'tpaint', 16:59:31 INFO - 'sessionrestore', 16:59:31 INFO - 'sessionrestore_no_auto_restore', 16:59:31 INFO - 'tabpaint']}, 16:59:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['a11yr', 16:59:31 INFO - 'ts_paint', 16:59:31 INFO - 'tpaint', 16:59:31 INFO - 'sessionrestore', 16:59:31 INFO - 'sessionrestore_no_auto_restore', 16:59:31 INFO - 'tabpaint']}, 16:59:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['tsvgx', 16:59:31 INFO - 'tsvgr_opacity', 16:59:31 INFO - 'tart', 16:59:31 INFO - 'tscrollx', 16:59:31 INFO - 'cart']}, 16:59:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:59:31 INFO - 'tsvgr_opacity', 16:59:31 INFO - 'tart', 16:59:31 INFO - 'tscrollx', 16:59:31 INFO - 'cart']}, 16:59:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:59:31 INFO - 'talos_options': ['--disable-e10s'], 16:59:31 INFO - 'tests': ['tp5o']}, 16:59:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:59:31 INFO - 'tests': ['tp5o']}, 16:59:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:59:31 INFO - 'talos_options': ['--disable-e10s', 16:59:31 INFO - '--xperf_path', 16:59:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:59:31 INFO - 'tests': ['tp5n']}, 16:59:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:59:31 INFO - 'talos_options': ['--xperf_path', 16:59:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:59:31 INFO - 'tests': ['tp5n']}}, 16:59:31 INFO - 'talos.zip': {'path': '', 16:59:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:59:32 INFO - Running post-action listener: _resource_record_post_action 16:59:32 INFO - [mozharness: 2016-10-12 23:59:32.000223Z] Finished populate-webroot step (success) 16:59:32 INFO - [mozharness: 2016-10-12 23:59:32.000334Z] Running create-virtualenv step. 16:59:32 INFO - Running pre-action listener: _resource_record_pre_action 16:59:32 INFO - Running main action method: create_virtualenv 16:59:32 INFO - Creating virtualenv /builds/slave/test/build/venv 16:59:32 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 16:59:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:59:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:59:32 INFO - Using real prefix '/tools/python27' 16:59:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:59:32 INFO - Installing distribute.............................................................................................................................................................................................done. 16:59:35 INFO - Installing pip.................done. 16:59:35 INFO - Return code: 0 16:59:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:59:35 INFO - Reading from file tmpfile_stdout 16:59:35 INFO - Output received: 16:59:35 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:59:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:59: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')]} 16:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59: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')]} 16:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:59:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 16:59:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:35 INFO - 'HOME': '/Users/cltbld', 16:59:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:35 INFO - 'LOGNAME': 'cltbld', 16:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:35 INFO - 'MOZ_NO_REMOTE': '1', 16:59:35 INFO - 'NO_EM_RESTART': '1', 16:59:35 INFO - 'PAGER': '/bin/cat', 16:59:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:35 INFO - 'PWD': '/builds/slave/test', 16:59:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:35 INFO - 'SHELL': '/bin/bash', 16:59:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 16:59:35 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 16:59:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:35 INFO - 'USER': 'cltbld', 16:59:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:35 INFO - 'XPC_FLAGS': '0x0', 16:59:35 INFO - 'XPC_SERVICE_NAME': '0', 16:59:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:36 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 16:59:36 INFO - Cleaning up... 16:59:36 INFO - Return code: 0 16:59:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 16:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:36 INFO - 'HOME': '/Users/cltbld', 16:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:36 INFO - 'LOGNAME': 'cltbld', 16:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:36 INFO - 'MOZ_NO_REMOTE': '1', 16:59:36 INFO - 'NO_EM_RESTART': '1', 16:59:36 INFO - 'PAGER': '/bin/cat', 16:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:36 INFO - 'PWD': '/builds/slave/test', 16:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:36 INFO - 'SHELL': '/bin/bash', 16:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 16:59:36 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 16:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:36 INFO - 'USER': 'cltbld', 16:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:36 INFO - 'XPC_FLAGS': '0x0', 16:59:36 INFO - 'XPC_SERVICE_NAME': '0', 16:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:36 INFO - Downloading/unpacking psutil>=3.1.1 16:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:59:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:59:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:59:39 INFO - Installing collected packages: psutil 16:59:39 INFO - Running setup.py install for psutil 16:59:39 INFO - building 'psutil._psutil_osx' extension 16:59:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:59:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:59:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:59:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:59:40 INFO - building 'psutil._psutil_posix' extension 16:59:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:59:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:59:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:59:40 INFO - ^ 16:59:40 INFO - 1 warning generated. 16:59:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:59:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:59:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:59:40 INFO - Successfully installed psutil 16:59:40 INFO - Cleaning up... 16:59:40 INFO - Return code: 0 16:59:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:59:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:59:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 16:59:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:40 INFO - 'HOME': '/Users/cltbld', 16:59:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:40 INFO - 'LOGNAME': 'cltbld', 16:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:40 INFO - 'MOZ_NO_REMOTE': '1', 16:59:40 INFO - 'NO_EM_RESTART': '1', 16:59:40 INFO - 'PAGER': '/bin/cat', 16:59:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:40 INFO - 'PWD': '/builds/slave/test', 16:59:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:40 INFO - 'SHELL': '/bin/bash', 16:59:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 16:59:40 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 16:59:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:40 INFO - 'USER': 'cltbld', 16:59:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:40 INFO - 'XPC_FLAGS': '0x0', 16:59:40 INFO - 'XPC_SERVICE_NAME': '0', 16:59:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:59:43 INFO - Installing collected packages: mozsystemmonitor 16:59:43 INFO - Running setup.py install for mozsystemmonitor 16:59:43 INFO - Successfully installed mozsystemmonitor 16:59:43 INFO - Cleaning up... 16:59:43 INFO - Return code: 0 16:59:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:59: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')]} 16:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59: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')]} 16:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59: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', '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 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:59: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 jsonschema==2.5.1 16:59:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 16:59:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:43 INFO - 'HOME': '/Users/cltbld', 16:59:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:43 INFO - 'LOGNAME': 'cltbld', 16:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:43 INFO - 'MOZ_NO_REMOTE': '1', 16:59:43 INFO - 'NO_EM_RESTART': '1', 16:59:43 INFO - 'PAGER': '/bin/cat', 16:59:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:43 INFO - 'PWD': '/builds/slave/test', 16:59:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:43 INFO - 'SHELL': '/bin/bash', 16:59:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 16:59:43 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 16:59:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:43 INFO - 'USER': 'cltbld', 16:59:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:43 INFO - 'XPC_FLAGS': '0x0', 16:59:43 INFO - 'XPC_SERVICE_NAME': '0', 16:59:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:43 INFO - Downloading/unpacking jsonschema==2.5.1 16:59: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 16:59: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 16:59: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 16:59: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 16:59: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 16:59: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 16:59:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:59:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:59:46 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 16:59:46 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 16:59:46 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 16:59:46 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 16:59:46 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 16:59:46 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 16:59:46 INFO - Downloading functools32-3.2.3-2.tar.gz 16:59:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:59:46 INFO - warning: no files found matching '*.txt' 16:59:46 INFO - no previously-included directories found matching 'build' 16:59:46 INFO - no previously-included directories found matching 'dist' 16:59:46 INFO - no previously-included directories found matching '.git*' 16:59:46 INFO - Installing collected packages: jsonschema, functools32 16:59:46 INFO - Running setup.py install for functools32 16:59:47 INFO - warning: no files found matching '*.txt' 16:59:47 INFO - no previously-included directories found matching 'build' 16:59:47 INFO - no previously-included directories found matching 'dist' 16:59:47 INFO - no previously-included directories found matching '.git*' 16:59:47 INFO - Successfully installed jsonschema functools32 16:59:47 INFO - Cleaning up... 16:59:47 INFO - Return code: 0 16:59:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:47 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 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:47 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 16:59:47 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 16:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 16:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:47 INFO - 'HOME': '/Users/cltbld', 16:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:47 INFO - 'LOGNAME': 'cltbld', 16:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:47 INFO - 'MOZ_NO_REMOTE': '1', 16:59:47 INFO - 'NO_EM_RESTART': '1', 16:59:47 INFO - 'PAGER': '/bin/cat', 16:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:47 INFO - 'PWD': '/builds/slave/test', 16:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:47 INFO - 'SHELL': '/bin/bash', 16:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 16:59:47 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 16:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:47 INFO - 'USER': 'cltbld', 16:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:47 INFO - 'XPC_FLAGS': '0x0', 16:59:47 INFO - 'XPC_SERVICE_NAME': '0', 16:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:59:47 INFO - Cleaning up... 16:59:47 INFO - Return code: 0 16:59:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:47 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 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:47 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 16:59:47 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 16:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 16:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:47 INFO - 'HOME': '/Users/cltbld', 16:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:47 INFO - 'LOGNAME': 'cltbld', 16:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:47 INFO - 'MOZ_NO_REMOTE': '1', 16:59:47 INFO - 'NO_EM_RESTART': '1', 16:59:47 INFO - 'PAGER': '/bin/cat', 16:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:47 INFO - 'PWD': '/builds/slave/test', 16:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:47 INFO - 'SHELL': '/bin/bash', 16:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 16:59:47 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 16:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:47 INFO - 'USER': 'cltbld', 16:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:47 INFO - 'XPC_FLAGS': '0x0', 16:59:47 INFO - 'XPC_SERVICE_NAME': '0', 16:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:47 INFO - Downloading/unpacking blobuploader==1.2.4 16:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:50 INFO - Downloading blobuploader-1.2.4.tar.gz 16:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:59:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:59:50 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 16:59:50 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 16:59:50 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 16:59:50 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 16:59:50 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 16:59:50 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 16:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:59:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:59:50 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 16:59:50 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 16:59:50 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 16:59:50 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 16:59:50 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 16:59:50 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 16:59:51 INFO - Downloading docopt-0.6.1.tar.gz 16:59:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:59:51 INFO - Installing collected packages: blobuploader, requests, docopt 16:59:51 INFO - Running setup.py install for blobuploader 16:59:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:59:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:59:51 INFO - Running setup.py install for requests 16:59:52 INFO - Running setup.py install for docopt 16:59:52 INFO - Successfully installed blobuploader requests docopt 16:59:52 INFO - Cleaning up... 16:59:52 INFO - Return code: 0 16:59:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:59:52 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')]} 16:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:59:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 16:59:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:53 INFO - 'HOME': '/Users/cltbld', 16:59:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:53 INFO - 'LOGNAME': 'cltbld', 16:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:53 INFO - 'MOZ_NO_REMOTE': '1', 16:59:53 INFO - 'NO_EM_RESTART': '1', 16:59:53 INFO - 'PAGER': '/bin/cat', 16:59:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:53 INFO - 'PWD': '/builds/slave/test', 16:59:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:53 INFO - 'SHELL': '/bin/bash', 16:59:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 16:59:53 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 16:59:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:53 INFO - 'USER': 'cltbld', 16:59:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:53 INFO - 'XPC_FLAGS': '0x0', 16:59:53 INFO - 'XPC_SERVICE_NAME': '0', 16:59:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:59:53 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-F6pbdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:59:53 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-YnIaYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:59:53 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-e7shIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:59:53 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-DKU5N7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-7shn6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-rb59YF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-YdS6Kh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-VzRqAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-wq4hOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-97cOjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-ERzP3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-BI687c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-pwUJxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:59:54 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-fepEbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:55 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-NzqO6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:59:55 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-1Os9Xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:59:55 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-k5AqoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:59:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:59:55 INFO - Running setup.py install for manifestparser 16:59:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:59:55 INFO - Running setup.py install for mozcrash 16:59:55 INFO - Running setup.py install for mozdebug 16:59:55 INFO - Running setup.py install for mozdevice 16:59:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:59:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:59:55 INFO - Running setup.py install for mozfile 16:59:56 INFO - Running setup.py install for mozhttpd 16:59:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:59:56 INFO - Running setup.py install for mozinfo 16:59:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:59:56 INFO - Running setup.py install for mozInstall 16:59:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:59:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:59:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:59:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:59:56 INFO - Running setup.py install for mozleak 16:59:56 INFO - Running setup.py install for mozlog 16:59:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:59:56 INFO - Running setup.py install for moznetwork 16:59:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:59:57 INFO - Running setup.py install for mozprocess 16:59:57 INFO - Running setup.py install for mozprofile 16:59:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:59:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:59:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:59:57 INFO - Running setup.py install for mozrunner 16:59:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:59:57 INFO - Running setup.py install for mozscreenshot 16:59:57 INFO - Running setup.py install for moztest 16:59:57 INFO - Running setup.py install for mozversion 16:59:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:59:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:59:57 INFO - Cleaning up... 16:59:57 INFO - Return code: 0 16:59:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:59:57 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')]} 16:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:57 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')]} 16:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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 16:59:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:59:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:59:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 16:59:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:58 INFO - 'HOME': '/Users/cltbld', 16:59:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:58 INFO - 'LOGNAME': 'cltbld', 16:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:58 INFO - 'MOZ_NO_REMOTE': '1', 16:59:58 INFO - 'NO_EM_RESTART': '1', 16:59:58 INFO - 'PAGER': '/bin/cat', 16:59:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:58 INFO - 'PWD': '/builds/slave/test', 16:59:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:58 INFO - 'SHELL': '/bin/bash', 16:59:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 16:59:58 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 16:59:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:58 INFO - 'USER': 'cltbld', 16:59:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:58 INFO - 'XPC_FLAGS': '0x0', 16:59:58 INFO - 'XPC_SERVICE_NAME': '0', 16:59:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:59:58 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-bIpXAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:59:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:59:58 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-NYu2cT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:59:58 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)) 16:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:59:58 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-BftOK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:59:58 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)) 16:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:59:58 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-xXI4Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:59:59 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)) 16:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:59:59 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-TrZBjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:59:59 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-QgR1Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:59:59 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-DGNx5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:59:59 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-ejzfsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:59:59 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-va8lze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:59:59 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-WG1FVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:59:59 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-dh6TGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:59:59 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-ZziMwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:59:59 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)) 16:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:59:59 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-TNi4AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:00:00 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-AagWdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:00:00 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:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:00 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-pLxUAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:00:00 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-23xkJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:00:00 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:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:00:00 INFO - Running setup.py (path:/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/pip-7FszlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:00:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:00:03 INFO - Downloading blessings-1.6.tar.gz 17:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:00:03 INFO - Installing collected packages: blessings 17:00:03 INFO - Running setup.py install for blessings 17:00:03 INFO - Successfully installed blessings 17:00:03 INFO - Cleaning up... 17:00:03 INFO - Return code: 0 17:00:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:00:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:00:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:00:03 INFO - Reading from file tmpfile_stdout 17:00:03 INFO - Current package versions: 17:00:03 INFO - blessings == 1.6 17:00:03 INFO - blobuploader == 1.2.4 17:00:03 INFO - docopt == 0.6.1 17:00:03 INFO - functools32 == 3.2.3-2 17:00:03 INFO - jsonschema == 2.5.1 17:00:03 INFO - manifestparser == 1.1 17:00:03 INFO - mozInstall == 1.12 17:00:03 INFO - mozcrash == 0.17 17:00:03 INFO - mozdebug == 0.1 17:00:03 INFO - mozdevice == 0.48 17:00:03 INFO - mozfile == 1.2 17:00:03 INFO - mozhttpd == 0.7 17:00:03 INFO - mozinfo == 0.9 17:00:03 INFO - mozleak == 0.1 17:00:03 INFO - mozlog == 3.4 17:00:03 INFO - moznetwork == 0.27 17:00:03 INFO - mozprocess == 0.23 17:00:03 INFO - mozprofile == 0.28 17:00:03 INFO - mozrunner == 6.12 17:00:03 INFO - mozscreenshot == 0.1 17:00:03 INFO - mozsystemmonitor == 0.3 17:00:03 INFO - moztest == 0.7 17:00:03 INFO - mozversion == 1.4 17:00:03 INFO - psutil == 3.1.1 17:00:03 INFO - requests == 1.2.3 17:00:03 INFO - wsgiref == 0.1.2 17:00:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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:00:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:00:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:00:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 17:00:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:03 INFO - 'HOME': '/Users/cltbld', 17:00:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:03 INFO - 'LOGNAME': 'cltbld', 17:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:03 INFO - 'MOZ_NO_REMOTE': '1', 17:00:03 INFO - 'NO_EM_RESTART': '1', 17:00:03 INFO - 'PAGER': '/bin/cat', 17:00:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:03 INFO - 'PWD': '/builds/slave/test', 17:00:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:03 INFO - 'SHELL': '/bin/bash', 17:00:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 17:00:03 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 17:00:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:03 INFO - 'USER': 'cltbld', 17:00:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:03 INFO - 'XPC_FLAGS': '0x0', 17:00:03 INFO - 'XPC_SERVICE_NAME': '0', 17:00:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:00:04 INFO - Cleaning up... 17:00:04 INFO - Return code: 0 17:00:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103581490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f940bd7c9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, '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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:00:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 17:00:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:04 INFO - 'HOME': '/Users/cltbld', 17:00:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:04 INFO - 'LOGNAME': 'cltbld', 17:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:04 INFO - 'MOZ_NO_REMOTE': '1', 17:00:04 INFO - 'NO_EM_RESTART': '1', 17:00:04 INFO - 'PAGER': '/bin/cat', 17:00:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:04 INFO - 'PWD': '/builds/slave/test', 17:00:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:04 INFO - 'SHELL': '/bin/bash', 17:00:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 17:00:04 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 17:00:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:04 INFO - 'USER': 'cltbld', 17:00:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:04 INFO - 'XPC_FLAGS': '0x0', 17:00:04 INFO - 'XPC_SERVICE_NAME': '0', 17:00:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:00:04 INFO - Cleaning up... 17:00:04 INFO - Return code: 0 17:00:04 INFO - Running post-action listener: _resource_record_post_action 17:00:04 INFO - Running post-action listener: _start_resource_monitoring 17:00:04 INFO - Starting resource monitoring. 17:00:04 INFO - [mozharness: 2016-10-13 00:00:04.444557Z] Finished create-virtualenv step (success) 17:00:04 INFO - [mozharness: 2016-10-13 00:00:04.445549Z] Running install step. 17:00:04 INFO - Running pre-action listener: _resource_record_pre_action 17:00:04 INFO - Running main action method: install 17:00:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:00:04 INFO - Reading from file tmpfile_stdout 17:00:04 INFO - Detecting whether we're running mozinstall >=1.0... 17:00:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:00:04 INFO - Reading from file tmpfile_stdout 17:00:04 INFO - Output received: 17:00:04 INFO - Usage: mozinstall [options] installer 17:00:04 INFO - Options: 17:00:04 INFO - -h, --help show this help message and exit 17:00:04 INFO - -d DEST, --destination=DEST 17:00:04 INFO - Directory to install application into. [default: 17:00:04 INFO - "/builds/slave/test"] 17:00:04 INFO - --app=APP Application being installed. [default: firefox] 17:00:04 INFO - mkdir: /builds/slave/test/build/application 17:00:04 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:00:04 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:00:39 INFO - Reading from file tmpfile_stdout 17:00:39 INFO - Output received: 17:00:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:00:39 INFO - Running post-action listener: _resource_record_post_action 17:00:39 INFO - [mozharness: 2016-10-13 00:00:39.537449Z] Finished install step (success) 17:00:39 INFO - [mozharness: 2016-10-13 00:00:39.537583Z] Running run-tests step. 17:00:39 INFO - Running pre-action listener: _resource_record_pre_action 17:00:39 INFO - Running main action method: run_tests 17:00:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:00:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:00:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:00:39 INFO - 2016-10-12 17:00:39.552 screenresolution[1940:16415] starting screenresolution argv=screenresolution get 17:00:39 INFO - 2016-10-12 17:00:39.554 screenresolution[1940:16415] Display 0: 1600x1200x32@60 17:00:39 INFO - 2016-10-12 17:00:39.560 screenresolution[1941:16417] starting screenresolution argv=screenresolution list 17:00:39 INFO - Available Modes on Display 0 17:00:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:00:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:00:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:00:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:00:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:00:39 INFO - Intel Iris: 17:00:39 INFO - Chipset Model: Intel Iris 17:00:39 INFO - Type: GPU 17:00:39 INFO - Bus: Built-In 17:00:39 INFO - VRAM (Dynamic, Max): 1536 MB 17:00:39 INFO - Vendor: Intel (0x8086) 17:00:39 INFO - Device ID: 0x0a2e 17:00:39 INFO - Revision ID: 0x0009 17:00:39 INFO - Displays: 17:00:39 INFO - Display: 17:00:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:00:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:00:39 INFO - Display Serial Number: 1600x1200 60 17:00:39 INFO - Main Display: Yes 17:00:39 INFO - Mirror: Off 17:00:39 INFO - Online: Yes 17:00:39 INFO - Rotation: Supported 17:00:39 INFO - Return code: 0 17:00:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:00:39 INFO - Python 2.7.3 17:00:39 INFO - Return code: 0 17:00:39 INFO - grabbing minidump binary from tooltool 17:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:00:39 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 0x103395d30>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10338db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:00:39 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:00:39 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:00:39 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:00:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:00:39 INFO - Return code: 0 17:00:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:00:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:00:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:00:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:00:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:00:39 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', '--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-0313', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:00:39 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 --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-0313 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:00:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tog6JK8tCp/Render', 17:00:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:39 INFO - 'HOME': '/Users/cltbld', 17:00:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:39 INFO - 'LOGNAME': 'cltbld', 17:00:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:00:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:39 INFO - 'MOZ_NO_REMOTE': '1', 17:00:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:00:39 INFO - 'NO_EM_RESTART': '1', 17:00:39 INFO - 'PAGER': '/bin/cat', 17:00:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:39 INFO - 'PWD': '/builds/slave/test', 17:00:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:00:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:39 INFO - 'SHELL': '/bin/bash', 17:00:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XU0WtpDPWx/Listeners', 17:00:39 INFO - 'TMPDIR': '/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/', 17:00:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:39 INFO - 'USER': 'cltbld', 17:00:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:39 INFO - 'XPC_FLAGS': '0x0', 17:00:39 INFO - 'XPC_SERVICE_NAME': '0', 17:00:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--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-0313', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:00:40 INFO - mozversion application_buildid: 20161012144547 17:00:40 INFO - mozversion application_changeset: d68def1644c1849141ceaa31bc1b811becfbb8da 17:00:40 INFO - mozversion application_display_name: Nightly 17:00:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:00:40 INFO - mozversion application_name: Firefox 17:00:40 INFO - mozversion application_remotingname: firefox 17:00:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:00:40 INFO - mozversion application_vendor: Mozilla 17:00:40 INFO - mozversion application_version: 52.0a1 17:00:40 INFO - mozversion platform_buildid: 20161012144547 17:00:40 INFO - mozversion platform_changeset: d68def1644c1849141ceaa31bc1b811becfbb8da 17:00:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:00:40 INFO - mozversion platform_version: 52.0a1 17:00:40 INFO - using testdate: 1476316840 17:00:40 INFO - actual date: 1476316840 17:00:40 INFO - starting webserver on 'localhost:52043' 17:00:40 INFO - SUITE-START | Running 2 tests 17:00:40 INFO - TEST-START | dromaeo_css 17:00:40 INFO - operating with platform_type : mac_ 17:00:40 INFO - Initialising browser for dromaeo_css test... 17:00:40 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpx5Ngbn/profile http://localhost:52043/getInfo.html) 17:00:42 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 17:00:42 INFO - PROCESS | 1951 | colorDepth:24 17:00:42 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 17:00:42 INFO - PROCESS | 1951 | __metrics 17:00:42 INFO - PROCESS | 1951 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:00:42 INFO - PROCESS | 1951 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:00:43 INFO - TEST-INFO | 1951: exit 0 17:00:43 INFO - Browser initialized. 17:00:43 INFO - Running cycle 1/1 for dromaeo_css test... 17:00:43 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmpx5Ngbn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:00:55 INFO - PROCESS | 1953 | RSS: Main: 235155456 17:00:55 INFO - PROCESS | 1953 | 17:01:47 INFO - PROCESS | 1953 | 1476316906994 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] 17:03:59 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52043/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52043/tests/dromaeo/cssquery-ext.html) 17:04:01 INFO - PROCESS | 1953 | RSS: Main: 405950464 17:04:01 INFO - PROCESS | 1953 | 17:07:04 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52043/tests/dromaeo/cssquery-ext.html (next: http://localhost:52043/tests/dromaeo/cssquery-jquery.html) 17:07:05 INFO - PROCESS | 1953 | RSS: Main: 407482368 17:07:05 INFO - PROCESS | 1953 | 17:10:07 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52043/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52043/tests/dromaeo/cssquery-mootools.html) 17:10:07 INFO - PROCESS | 1953 | RSS: Main: 347893760 17:10:07 INFO - PROCESS | 1953 | 17:13:10 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52043/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52043/tests/dromaeo/cssquery-prototype.html) 17:13:10 INFO - PROCESS | 1953 | RSS: Main: 339488768 17:13:10 INFO - PROCESS | 1953 | 17:16:14 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52043/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52043/tests/dromaeo/cssquery-yui.html) 17:16:14 INFO - PROCESS | 1953 | RSS: Main: 327667712 17:16:14 INFO - PROCESS | 1953 | 17:19:18 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:52043/tests/dromaeo/cssquery-yui.html (next: http://localhost:52043/tests/dromaeo/cssquery-dojo.html) 17:19:18 INFO - PROCESS | 1953 | RSS: Main: 470560768 17:19:18 INFO - PROCESS | 1953 | 17:19:18 INFO - PROCESS | 1953 | __start_tp_report 17:19:18 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 17:19:18 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 17:19:18 INFO - PROCESS | 1953 | |i|pagename|runs| 17:19:18 INFO - PROCESS | 1953 | |0;dojo.html;3147.6779752503953;3158.778885478016;3271.721903638191;3289.0461766087838;3306.5370848081257;5424.430434804348;5544.974179776744;5554.1391084381885;5628.099504079343;5676.120201368789;5549;5560.582956278275;5652.236948012028;5666;5699.088145896647;5038.722870242135;5066.873328166788;5074.949250507503;5121.257417674435;5162.303089082963;5190.169572868342;5247.002493369237;5252.212168174785;5255.632105752599;5259.947400526004;4365.388845561624;5195.558377537914;5227.163653815409;5232.16285394337;5246.134387826023;3592.784432934006;5078.263651770472;5098.362704661918;5151.896962060738;5162.819301324474;5057.468965758616;5103.744812759384;5105.642605017651;5123.692578445305;5133.460986596391;4623.422072240936;5566.387697353288;5594.636348637367;5616.831495055155;5617.8033768817895;6097.040762675677;6099.097936865534;6100.732564372323;6101.29763069421;6124.7243874025335;6125.847626645375;6132.63086471492;6155.815325540241;6178.814635560941;6188.350223226541;5607.4112218217315;5647.6893770843;5650.152477128464;5776.046952252873;5776.075827867563;6293.748250069946;6333.429991300805;6334.208223972004;6339.270983836917;6342.175517182829;5886.470217680429;6113.908291375633;6116.908246376308;6118.969405152946;6120.357362476876;6103.176071230416;6133.172021777093;6135.7291609442955;6136.8772624547255;6143.634700640197;6147.538934579924;6159.6612186330185;6166.229221347332;6176.7838125666285;6179.412955769195;6169.814905552751;6175.96912015446;6181.907271390933;6207.503399728011;6209.534284928648;4765.880018195721;4816.470188279286;4822.42130944289;4845.442774080955;4887.902241955212;4389.3415987601475;4396.208682437128;4414.492333381638;4417.249067658506;4460.933086003713;5648.350439699487;5658.688772117491;5661.1508273758445;5667.1499275108235;5672.14917762339;6093.969530152321;6132.080177995925;6140.289530736458;6181.660008699475;6261.092141639437;5600.747966341524;5613.382527921925;5633.887322253532;5685.948099601587;5746.913796293059;5379.677219366772;5382.542483888913;5382.650127741685;5387.218853266255;5390.946090539122;6206.472449841814;6310.9368906311265;6324.588901721373;6344.333844946399;6344.429001389989;6037.45662890342;6136.978641397139;6167.5682702210415;6175;6187.102870083902;6255.24937007562;6289.874202516015;6310.968445157929;6317.210348706411;6322.652254126067;6113.663748493876;6118.816435506942;6155.846103847261;6167.722452489559;6175.6912154393;6076;6125.234345706786;6252.968260237192;6258.12386265914;6284.591501552384;5891.23413956183;5932.347441781487;5993.67034813072;6065.909011364744;6097.695115244309;5858.238429004202;5992.221011268507;6002.87994240104;6015.578909476295;6016.548758843017;5663.490285874291;5806.303243610793;5807.419258074327;5851.385604511635;5876.559258055749;5784.248047753765;5955.970220148872;5970.223870896929;5996.220491336098;6116.113163591343;5679.886402271849;5861.706914654335;5866;5874.676892770939;5904.557158213237 17:19:18 INFO - PROCESS | 1953 | |1;ext.html;456.7944425008746;483.22924934729093;523.2726510150891;523.9004589128066;550.0786183143234;30634.08097757068;38678.03304917375;39945.60054399455;40004.79997600008;40190.7990460048;41224;41534.792326038405;41667.58332416675;41678.16643667125;41680.332786688494;9446.149846513812;9582.425054496718;9617.42295462271;9618.51907404627;9626.278029147807;9411;9490.81018379632;9496;9509.667161649344;9516.762080947963;9466.337356385056;9519.714408567754;9530.80938381232;9547.522623868814;9576.090271424238;9483.525823708822;9523.904760952371;9531.618735250617;9534.809303813885;9560.139587437165;9447.52762361886;9539.52302384878;9545.665901693408;9546.809063818755;9553.474558899257;39005;39292.60707392918;39305;39315.606843931484;39355.40966885499;3150.3069324748517;3184.3472088222093;3349.676877633321;3591.8742844000335;3868.207017561393;29620.111396658136;30323.393532129456;30845;30898;31127.754889804382;3148.370325934822;3151.936961260762;3161.146490447566;3174.761892858045;3185.3470038642254;372701;377026.1148694239;377819;377961;378812;55232.17151742727;55350;55365;55486.72256638691;55542.16686749702;31426.685733142374;31860.362792744014;58112.41887581155;58187;58248;12341.75316493665;15994.680106397807;16011;16034;16049.358025679076;12295.508179672714;12448.128630995925;12453.1905426147;12461.75076498465;12465.189762665397;17934.372296969545;17938.013409262618;17978.910105449388;18547.846866032807;18559.44321670352;6356.872862542723;7695.884561731578;7732.226777322223;7807.258310460497;7849.686012559548;857.5412154497315;948.2129832239225;955.507913424587;1001.4642166441065;1088.8257878739523;28437.289067773472;29144;29303.706962930097;30057;30114.69885301119;838.4047326398204;1048.2714513413257;1061.3843970497094;1103.227740581596;1130.9377984210948;14453.710925781426;14899;14953.102813831205;15254;15279.923600381928;626674;938542;947048;947139;947554;45746;46335.768321158175;46341.30488042682;46410.535894641296;46419.07161856744;1207.7463732616072;1251.8247445357658;1253.59258241072;1333.9399727012308;1359.3814814259488;28665.42669146647;29945;30457.54313685252;31447.685523144475;31999.840000799846;46198.07603847837;52346.73826630843;52842;52905.47094529006;53020;528994;531258;531493;531860;532211;26141.0850620235;26339.73660263373;26620.201393958214;26729;26756.464870702086;44943.55056449393;45418;45552.316715248635;46140.53859461362;46180.07639847251;30498.84750576233;36201;37661.81169094137;38585.421218682306;38715.41926871043;36527.08682282964;37114.628853712195;37182;37934.24131517409;38310.42534362042;31322.686773131976;31598.684013160495;32090;34401.82799086089;37196;30001.249968750955;32794.50808237926;32873.67126328706;32894.50658240176;33087.83456082704;24552;24556.754432455447;24722.876385617958;25180.866860990933;25634.743652563237 17:19:18 INFO - PROCESS | 1953 | |2;jquery.html;367.36445948509095;371.715637537284;380.62508429197237;388.974716643418;399.65030598226554;18534.629307413845;19868.40394788155;20183.293584724503;20647.896760516178;20859.37421877346;20377.898110509428;20654.69017964728;20770.376888693325;20977.16091356344;21268;5864.912026319608;5984.192134061889;5993.520518358521;6027.457528822405;6177.166082578839;5873.1190321451695;6013.669248191348;6014.8797024059495;6023;6051.969740151293;5679.971600142014;5928.110783382484;5961.284645842505;5982.880342393171;6001.189839371673;5828;5929.673867937283;5985.401459854002;5987.491063259628;6006.249218847644;5144.382674079135;5194.428612852583;5208.322918020633;5224.582033437316;5225.451327610622;19629;25858.094966676264;25896.50397196373;25900.96396144152;26215.34461638455;2178.5098352870637;2221.677856710768;2365.207655435431;2368.810495160383;2429.7327293997646;15342.693146137015;15532.534023979299;15584.844151558565;15929.442469513628;16246.268917898722;1836.5867679772077;1843.4653950354382;1867.794542600313;1927.2194761121768;1927.8457723382237;116786.83213167844;117396.82603173945;117445;117459.23811142839;119676;10310.278280520291;10543.630972915911;10574.101201397967;10835.891641083646;11043.889561104446;11288.661340159808;13554.118982266122;13563.660908477366;13585.864141358657;13604.659883502794;7845.333146682482;8081.515109093473;8142.796430089295;8219.753407397668;8258;7874.409419293462;7874.685012599546;7922.405819563556;8050.597470126588;8055.7583272501915;5807.12893066035;6062.90905636407;6423.940049891809;6564.442022428051;6676.96661516699;1539.9153046582546;1824.2976454065015;1826.7259911013455;1826.963460730778;1830.9633807324046;902.8781114549452;990.9752256193509;1005.7284533176002;1018.1854516387009;1074.7420619051372;21540.892295538422;22272.55454890893;22273.66589501159;22280.99735511906;22349.553008940056;933.8179055084329;937.4984383355009;946.3659348236698;1008.2690049713899;1047.528612124547;10903.909609039032;11464.885351146548;11594.94202528982;11697.590584329508;11711.355875426763;509207;565746.5414056295;576482;581945;619583;82533;103670.48164759128;112606;115956;116311;953.1231267234161;975.2685485885586;1017.8473229015707;1018.0735530667057;1019.1439191079529;13113.803292950422;19524.121414536665;19923.701144482555;20048.799512004694;20432.48918776983;40569.17832490627;54230.45769542254;55439;55591.722041389534;55907.44092559022;79103.76132296243;80314;80782;81281.78077328963;82550;7722.3275776026;7739;7747.30274275318;8182.959085204536;8285.295749861329;11109.833467486122;12372.876271237172;13002.219866808022;13018.088733788543;13088.0838341319;10755.750273822689;11170.994610485095;11209.99110080064;11296.774064518828;11395.259308145276;10883.945580272048;10985.505652245509;11209.215354925076;11301.547938082385;11359.977602015859;8925.464472131693;11087;11165.609203677832;11394.94302528482;11440.313581185155;7802.492837965741;7900.604969751605;7933.8413231736195;7978.960105199437;8040.2361775631225;8667.306615470874;10884.61903833362;11085.390303533382;11088.44557772092;11117.055050320814 17:19:18 INFO - PROCESS | 1953 | |3;mootools.html;1344.3076815440045;1387.07066265602;1451.1728314860532;1596.6647004129131;1900.4108726294849;7775.455718099728;8236.764485327203;8613.353998450119;8879.822403551942;8885.289176865852;8733.91266087339;8800.163984421486;8811.823763524726;8815.955920220405;8816.691415800447;2324.976750232497;2353.482233908546;2358.870262135582;2359.1507057459385;2366.0890557135453;2313.791758741713;2341.94145146371;2361.8700971446565;2362.2795047510504;2362.2795047510504;2308.7922087012166;2327.7439481657;2330.8018818400456;2347.5187586544803;2347.6126439137606;2299.367673889677;2322.5703244899746;2326.4532834783813;2329.266281121458;2333.929982100523;2308.6421604651305;2315.583195024895;2318.420394901275;2323.802476789458;2326.6626339180893;8774.912250877473;8843.982941961693;8863.955680221623;8875.979262384779;8877.911220887772;5168.250603662485;5202.635815492917;5381.623286369956;5386.973065134688;5399.217113518518;3444.6383129771266;3608.675219230282;3638.43604241343;3647.6352364763575;3648.4892115103908;1047.204124865108;1050.0916707048425;1064.8030114428855;1065.712257690427;1074.1621535202555;11565.653030409017;11780.175387722862;11794.233374830608;11810.468528916219;11834.585789497327;4451.554844515523;4479.888002799956;4652.651051171176;4671.019085991979;4678.345031695565;8302.252797248277;9106.86339704905;9120.58957346921;9133.77165570866;9141.817163656822;5002.57478114357;5282.920756188661;5289.629725919149;5292.8147514837565;5293.735313234323;3963.4847469829315;4115.52671442782;4403.581659742319;4592.678512504092;4919.631027672939;3753.3994560870674;3766.4350347448103;3825.617438256152;3828.4831547741255;3837.9040523986564;1118.6811758648746;1127.3799410324452;1132.9716757080973;1139.8062329403856;1153.8615366155948;826.995865020683;843.4328113266715;896.5427631907774;906.6237511432813;928.9581968811419;5617.325920889438;5766.596338256281;5786.04530252512;5925.200097986803;5959.165716799652;768.6617888129206;914.9432405571575;931.4271722890373;938.5260443476001;1004.4375149915925;3831.272058308912;3836.693064554829;3862.8454861805776;3971.225611005884;3998.660113890352;10496.160307175407;10869.40218287986;10908.727281818017;10930.289531180448;10934.343939363664;10777.299475534066;10865.967733065347;10867.130629549618;10867.565297388015;10871.83692244617;7740.187280335484;8145;8159.388045896581;8169.223923727236;8316.251537361666;5896.528277737856;5934.406559344031;6055.2430946131735;6084.969575152273;6159.4456498915315;10077.294589378673;10201.693949181536;10241.897581024094;10248.38509689421;10261.794764104825;8832.867506987528;9879.160271377013;10198.541065651914;10208.132308753839;10390.896091039296;5504.4770746778995;7523.247675232433;7536;7585.582792946441;7587.393008559412;8252.917470825216;8308.210719981593;8336.124706905688;8396.244338009608;8443.282321002784;7509.774706758806;7550.622468876424;7591.696332146652;7592.772216833504;7639.694412223671;7072.858542829012;7294.927050729425;7299.5620262784405;7440.479166458296;7557.206493318123;6741.359570840762;6990.580565166107;7108.573485590881;7131.25121862197;7131.893021604784;4857.732824694675;4953.455119936876;4955.529224723646;5020.899582008266;5153.793848246178;5005.449400565862;5057.898842023213;5061.646334454349;5085.694858308513;5188.273641690091 17:19:18 INFO - PROCESS | 1953 | |4;prototype.html;303.5189225078249;303.8131549097305;312.8560862003478;317.6997737138405;319.65477284532705;5303.011370735974;5333.4399888011685;5363;5401.27082843817;5425.5117039466395;4166.9374959375555;4239.9576004240025;5073.7209453480045;5233.1103712368895;5326.0587165497545;4030.375291829771;4042.2319759245797;4048.781218930957;4059.776712280823;4083.6120568546016;3271.8854840080603;3981.920361592781;4039.4950631171105;4055.9797201013953;4077.531083925342;3285;3299.389612921605;3882.1559759114475;3994.3009973254566;4046.534648515414;4044.705694177864;4050.3721923101966;4069.4506241657296;4085.754854708727;4103.199876024177;2901.8984335548153;3508.824558772051;3518.8064656443885;3523.5243242162237;3529.894103176883;4071.837126514936;5109.897802043938;5134.332536770234;5192.961407718433;5210.114280572274;4517.819287228507;4530;4569.405977222939;4582.5417458253905;4591.908161836778;4322.546132656057;5576.498115169654;5592.412796656374;5594.496495315441;5622.381538030813;3363.6131844837914;3796.962030379716;3809.552370099372;3821.025638462202;3836.1943991761764;5547.338756420783;5831.912521312183;5871.295444546639;5877.324107727581;5916.3023110555905;3833;3842.538895332578;3848.5958974307296;3858.749181303206;3868.1103346230525;3910.7262491625884;3929.1945151243926;3929.3713005918917;3948.3287841067086;3958.5447673517338;3431.2294331593043;4368.912621747547;4479.7984090715345;4527.61515271199;4652.6975746577045;5577.581681373913;5592.217089607459;5593.20339830085;5596.916046259309;5628.409017053151;1212.7392610588765;1249.6563445052684;1302.446460254388;1311.560627189902;1333.7332533493145;635.3360738028771;647.4043879630751;771.124773382204;775.8874963130428;783.2246076384339;4301.19349873054;4803.423589169252;4837.854864354075;5247.55395791362;5287.9735601321745;4727.5745182933015;5583.771570254537;5734.541236701054;5753.015209752629;5766.855828604318;4487.596116349544;4615.469221039556;4917.87705307363;5116.334876466036;5229.4770522948165;2597.532444160069;2963.866626001835;2966.2287805170813;2970.420767950272;2978.955315670267;4968.90062198754;5551.7799676187715;5803.8549036274435;5908.379620139909;5933.465988061095;5717.685527296038;5735.8852822944145;5762.88804045819;5763.723329284909;5841.7371218295275;5044.848654540297;5846.649201047951;5850.912236316459;5873.2658417697785;5884.382139875337;5272.6309158358545;5557.527610153183;5648.491635752802;5671.489565939085;5723;5572.1303528683575;5586.748596313257;5594.300712410949;5602.159676048625;5616.606837521498;5089.796408143633;5396.790503407192;5785.8467135410465;5810.414365607264;5902.114682797443;2992.730654241129;3891.9297193272077;3901.7463864848696;3929.2534418461546;3930.469386632768;4777.948851252719;5049.495050494921;5109.821156259513;5340.839774806762;5465.824847657773;5046.59627229828;5448.83653490396;5456.809011684572;5466.945330546644;5470.617056805985;4650.793808196932;4885.828995985124;4964.304997300309;5358.866028349323;5399.625234228607;4534.460702006887;5200.323957885451;5245.501677340647;5253.343332083489;5258.842234732964;4595.333676616805;4853.51464853526;5121.925620123856;5212.791488340576;5492.725363731877;4988.301637770643;5281.069006701317;5322.441143679858;5398.703071331114;5410.161424979135 17:19:18 INFO - PROCESS | 1953 | |5;yui.html;715.8460930899859;729.7409419656022;744.7505085796257;756.9629607437811;786.665667091486;15379.384824607016;15619.453319133832;15634.452794152206;15640.296186671612;15672.138032408227;13885.583432497042;13931.164130152198;13965.231912244848;13967.720645587107;13980.02139850211;616.8797084568511;667.5727534378002;688.3838964127103;820.3560205238889;845.564534264854;843.2705709561246;846.496334680867;851.4976164063202;853.4665833853842;856.9143085691443;900.3967341880923;901.5086777706133;902.7697937026049;903.6972614174226;904.9592768325441;705.2348202200618;725.2892165677665;891.2023738753811;891.7725979875123;892.4689809563299;537.6693333599808;538.6902531044665;540.4568408749221;647.9190101237346;665.431056446736;14842.554723358317;14892.553223403207;14914.552563423114;14919.179445130514;14929.552113436614;13710.931445342809;13927.512537061255;13983.37074831635;14005.229712365815;14244.786328205086;12310;12478.00175985919;12648.49406023758;12826.166299190523;13150.539731109366;4431.822727090913;4476.485204201526;4539.7049191802425;4647.209974304343;4664.766761661904;105870;110864;111980.44009779899;112560;112774.43612781966;10699.358038517714;10924.399158046224;10927.781444371069;11196.888031119584;11286.492107855167;21944.561108777736;22070.66893996591;22076.779232207795;22090.779092209195;22185.556288874133;7850.411219158586;7861.882071768814;7872.724454644174;7905.051393832777;8055.436119471581;4215.683823713233;4229;4268.615824575803;4359.956400435955;4421.314696222091;3607.1162565171703;3751.4185301278344;3759.548854137466;3761.6614504694708;3761.7931013793955;678.9117414736053;679.7824696097201;680.9829754256084;728.300831202052;735.8234023834349;559.7089513453063;615.6829232945037;631.7062565906783;649.8277956341574;664.9261442769891;9208.539573021322;9361.297902657192;9459.763505912408;9543.809123817486;9544.291851888616;544.371251204859;607.4290167242777;618.5731845026917;638.3488841381765;692.1348314606741;4200.558941311179;4556.407667003268;4620.699654522446;4636.5595268450115;4748.643851711066;128756;129450;133648.66351336363;141849;148173;121846;122494.77505225012;123029;125008;130098;95417.52291238638;95543;113957;113995.43002285101;115701.01680680644;8650;8939.642414303355;9266.490343030928;9783.41299522031;9849.95075024649;48579;54527.63876876571;59177.22468325957;64830.67584662046;66329.00506492503;86998.5650071771;88559.22881542204;90430.0956990448;92107;93086;13175.407106680414;13681.79477307861;13711.382987765774;13729.862701372858;14175.298804780876;14467.204303763394;14808.481703140338;14830.995631179738;14877.702445951238;15055.849441505883;13508.459661613426;13783.310834458438;13787.241701706102;13836.169829810244;14283.714325713636;13357.599272021855;13576.79634805459;13813.723725525635;13976.161430313807;14076.929615351857;13388.665283367603;13500.797488037884;13501.864981350061;14008.859911401163;14169.929150354183;10690.343533057086;11236.606718764804;11304.491297891778;11512.36681982499;11579.278869465837;9918.950405247928;10276.640317588848;10289.382637041801;11280;11613 17:19:18 INFO - PROCESS | 1953 | __end_tp_report 17:19:18 INFO - PROCESS | 1953 | __start_cc_report 17:19:18 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,1902 17:19:18 INFO - PROCESS | 1953 | __end_cc_report 17:19:18 INFO - PROCESS | 1953 | __startTimestamp1476317958261__endTimestamp 17:19:18 INFO - PROCESS | 1953 | 17:19:18 INFO - PROCESS | 1953 | ------- Summary: start ------- 17:19:18 INFO - PROCESS | 1953 | Number of tests: 6 17:19:18 INFO - PROCESS | 1953 | 17:19:18 INFO - PROCESS | 1953 | [#0] dojo.html Cycles:165 Average:5659.13 Median:5861.71 stddev:674.16 (11.5%) stddev-sans-first:646.80 17:19:18 INFO - PROCESS | 1953 | Values: 3147.7 3158.8 3271.7 3289.0 3306.5 5424.4 5545.0 5554.1 5628.1 5676.1 5549.0 5560.6 5652.2 5666.0 5699.1 5038.7 5066.9 5074.9 5121.3 5162.3 5190.2 5247.0 5252.2 5255.6 5259.9 4365.4 5195.6 5227.2 5232.2 5246.1 3592.8 5078.3 5098.4 5151.9 5162.8 5057.5 5103.7 5105.6 5123.7 5133.5 4623.4 5566.4 5594.6 5616.8 5617.8 6097.0 6099.1 6100.7 6101.3 6124.7 6125.8 6132.6 6155.8 6178.8 6188.4 5607.4 5647.7 5650.2 5776.0 5776.1 6293.7 6333.4 6334.2 6339.3 6342.2 5886.5 6113.9 6116.9 6119.0 6120.4 6103.2 6133.2 6135.7 6136.9 6143.6 6147.5 6159.7 6166.2 6176.8 6179.4 6169.8 6176.0 6181.9 6207.5 6209.5 4765.9 4816.5 4822.4 4845.4 4887.9 4389.3 4396.2 4414.5 4417.2 4460.9 5648.4 5658.7 5661.2 5667.1 5672.1 6094.0 6132.1 6140.3 6181.7 6261.1 5600.7 5613.4 5633.9 5685.9 5746.9 5379.7 5382.5 5382.7 5387.2 5390.9 6206.5 6310.9 6324.6 6344.3 6344.4 6037.5 6137.0 6167.6 6175.0 6187.1 6255.2 6289.9 6311.0 6317.2 6322.7 6113.7 6118.8 6155.8 6167.7 6175.7 6076.0 6125.2 6253.0 6258.1 6284.6 5891.2 5932.3 5993.7 6065.9 6097.7 5858.2 5992.2 6002.9 6015.6 6016.5 5663.5 5806.3 5807.4 5851.4 5876.6 5784.2 5956.0 5970.2 5996.2 6116.1 5679.9 5861.7 5866.0 5874.7 5904.6 17:19:18 INFO - PROCESS | 1953 | 17:19:18 INFO - PROCESS | 1953 | [#1] ext.html Cycles:180 Average:71171.04 Median:38447.92 stddev:172269.03 (448.1%) stddev-sans-first:172670.47 17:19:18 INFO - PROCESS | 1953 | Values: 456.8 483.2 523.3 523.9 550.1 30634.1 38678.0 39945.6 40004.8 40190.8 41224.0 41534.8 41667.6 41678.2 41680.3 9446.1 9582.4 9617.4 9618.5 9626.3 9411.0 9490.8 9496.0 9509.7 9516.8 9466.3 9519.7 9530.8 9547.5 9576.1 9483.5 9523.9 9531.6 9534.8 9560.1 9447.5 9539.5 9545.7 9546.8 9553.5 39005.0 39292.6 39305.0 39315.6 39355.4 3150.3 3184.3 3349.7 3591.9 3868.2 29620.1 30323.4 30845.0 30898.0 31127.8 3148.4 3151.9 3161.1 3174.8 3185.3 372701.0 377026.1 377819.0 377961.0 378812.0 55232.2 55350.0 55365.0 55486.7 55542.2 31426.7 31860.4 58112.4 58187.0 58248.0 12341.8 15994.7 16011.0 16034.0 16049.4 12295.5 12448.1 12453.2 12461.8 12465.2 17934.4 17938.0 17978.9 18547.8 18559.4 6356.9 7695.9 7732.2 7807.3 7849.7 857.5 948.2 955.5 1001.5 1088.8 28437.3 29144.0 29303.7 30057.0 30114.7 838.4 1048.3 1061.4 1103.2 1130.9 14453.7 14899.0 14953.1 15254.0 15279.9 626674.0 938542.0 947048.0 947139.0 947554.0 45746.0 46335.8 46341.3 46410.5 46419.1 1207.7 1251.8 1253.6 1333.9 1359.4 28665.4 29945.0 30457.5 31447.7 31999.8 46198.1 52346.7 52842.0 52905.5 53020.0 528994.0 531258.0 531493.0 531860.0 532211.0 26141.1 26339.7 26620.2 26729.0 26756.5 44943.6 45418.0 45552.3 46140.5 46180.1 30498.8 36201.0 37661.8 38585.4 38715.4 36527.1 37114.6 37182.0 37934.2 38310.4 31322.7 31598.7 32090.0 34401.8 37196.0 30001.2 32794.5 32873.7 32894.5 33087.8 24552.0 24556.8 24722.9 25180.9 25634.7 17:19:18 INFO - PROCESS | 1953 | 17:19:18 INFO - PROCESS | 1953 | [#2] jquery.html Cycles:180 Average:33827.69 Median:22277.33 stddev:95183.56 (427.3%) stddev-sans-first:95417.41 17:19:18 INFO - PROCESS | 1953 | Values: 367.4 371.7 380.6 389.0 399.7 18534.6 19868.4 20183.3 20647.9 20859.4 20377.9 20654.7 20770.4 20977.2 21268.0 5864.9 5984.2 5993.5 6027.5 6177.2 5873.1 6013.7 6014.9 6023.0 6052.0 5680.0 5928.1 5961.3 5982.9 6001.2 5828.0 5929.7 5985.4 5987.5 6006.2 5144.4 5194.4 5208.3 5224.6 5225.5 19629.0 25858.1 25896.5 25901.0 26215.3 2178.5 2221.7 2365.2 2368.8 2429.7 15342.7 15532.5 15584.8 15929.4 16246.3 1836.6 1843.5 1867.8 1927.2 1927.8 116786.8 117396.8 117445.0 117459.2 119676.0 10310.3 10543.6 10574.1 10835.9 11043.9 11288.7 13554.1 13563.7 13585.9 13604.7 7845.3 8081.5 8142.8 8219.8 8258.0 7874.4 7874.7 7922.4 8050.6 8055.8 5807.1 6062.9 6423.9 6564.4 6677.0 1539.9 1824.3 1826.7 1827.0 1831.0 902.9 991.0 1005.7 1018.2 1074.7 21540.9 22272.6 22273.7 22281.0 22349.6 933.8 937.5 946.4 1008.3 1047.5 10903.9 11464.9 11594.9 11697.6 11711.4 509207.0 565746.5 576482.0 581945.0 619583.0 82533.0 103670.5 112606.0 115956.0 116311.0 953.1 975.3 1017.8 1018.1 1019.1 13113.8 19524.1 19923.7 20048.8 20432.5 40569.2 54230.5 55439.0 55591.7 55907.4 79103.8 80314.0 80782.0 81281.8 82550.0 7722.3 7739.0 7747.3 8183.0 8285.3 11109.8 12372.9 13002.2 13018.1 13088.1 10755.8 11171.0 11210.0 11296.8 11395.3 10883.9 10985.5 11209.2 11301.5 11360.0 8925.5 11087.0 11165.6 11394.9 11440.3 7802.5 7900.6 7933.8 7979.0 8040.2 8667.3 10884.6 11085.4 11088.4 11117.1 17:19:18 INFO - PROCESS | 1953 | 17:19:18 INFO - PROCESS | 1953 | [#3] mootools.html Cycles:180 Average:5669.02 Median:4954.49 stddev:3205.37 (64.7%) stddev-sans-first:3197.88 17:19:18 INFO - PROCESS | 1953 | Values: 1344.3 1387.1 1451.2 1596.7 1900.4 7775.5 8236.8 8613.4 8879.8 8885.3 8733.9 8800.2 8811.8 8816.0 8816.7 2325.0 2353.5 2358.9 2359.2 2366.1 2313.8 2341.9 2361.9 2362.3 2362.3 2308.8 2327.7 2330.8 2347.5 2347.6 2299.4 2322.6 2326.5 2329.3 2333.9 2308.6 2315.6 2318.4 2323.8 2326.7 8774.9 8844.0 8864.0 8876.0 8877.9 5168.3 5202.6 5381.6 5387.0 5399.2 3444.6 3608.7 3638.4 3647.6 3648.5 1047.2 1050.1 1064.8 1065.7 1074.2 11565.7 11780.2 11794.2 11810.5 11834.6 4451.6 4479.9 4652.7 4671.0 4678.3 8302.3 9106.9 9120.6 9133.8 9141.8 5002.6 5282.9 5289.6 5292.8 5293.7 3963.5 4115.5 4403.6 4592.7 4919.6 3753.4 3766.4 3825.6 3828.5 3837.9 1118.7 1127.4 1133.0 1139.8 1153.9 827.0 843.4 896.5 906.6 929.0 5617.3 5766.6 5786.0 5925.2 5959.2 768.7 914.9 931.4 938.5 1004.4 3831.3 3836.7 3862.8 3971.2 3998.7 10496.2 10869.4 10908.7 10930.3 10934.3 10777.3 10866.0 10867.1 10867.6 10871.8 7740.2 8145.0 8159.4 8169.2 8316.3 5896.5 5934.4 6055.2 6085.0 6159.4 10077.3 10201.7 10241.9 10248.4 10261.8 8832.9 9879.2 10198.5 10208.1 10390.9 5504.5 7523.2 7536.0 7585.6 7587.4 8252.9 8308.2 8336.1 8396.2 8443.3 7509.8 7550.6 7591.7 7592.8 7639.7 7072.9 7294.9 7299.6 7440.5 7557.2 6741.4 6990.6 7108.6 7131.3 7131.9 4857.7 4953.5 4955.5 5020.9 5153.8 5005.4 5057.9 5061.6 5085.7 5188.3 17:19:18 INFO - PROCESS | 1953 | 17:19:18 INFO - PROCESS | 1953 | [#4] prototype.html Cycles:180 Average:4456.46 Median:5045.72 stddev:1382.14 (27.4%) stddev-sans-first:1350.41 17:19:18 INFO - PROCESS | 1953 | Values: 303.5 303.8 312.9 317.7 319.7 5303.0 5333.4 5363.0 5401.3 5425.5 4166.9 4240.0 5073.7 5233.1 5326.1 4030.4 4042.2 4048.8 4059.8 4083.6 3271.9 3981.9 4039.5 4056.0 4077.5 3285.0 3299.4 3882.2 3994.3 4046.5 4044.7 4050.4 4069.5 4085.8 4103.2 2901.9 3508.8 3518.8 3523.5 3529.9 4071.8 5109.9 5134.3 5193.0 5210.1 4517.8 4530.0 4569.4 4582.5 4591.9 4322.5 5576.5 5592.4 5594.5 5622.4 3363.6 3797.0 3809.6 3821.0 3836.2 5547.3 5831.9 5871.3 5877.3 5916.3 3833.0 3842.5 3848.6 3858.7 3868.1 3910.7 3929.2 3929.4 3948.3 3958.5 3431.2 4368.9 4479.8 4527.6 4652.7 5577.6 5592.2 5593.2 5596.9 5628.4 1212.7 1249.7 1302.4 1311.6 1333.7 635.3 647.4 771.1 775.9 783.2 4301.2 4803.4 4837.9 5247.6 5288.0 4727.6 5583.8 5734.5 5753.0 5766.9 4487.6 4615.5 4917.9 5116.3 5229.5 2597.5 2963.9 2966.2 2970.4 2979.0 4968.9 5551.8 5803.9 5908.4 5933.5 5717.7 5735.9 5762.9 5763.7 5841.7 5044.8 5846.6 5850.9 5873.3 5884.4 5272.6 5557.5 5648.5 5671.5 5723.0 5572.1 5586.7 5594.3 5602.2 5616.6 5089.8 5396.8 5785.8 5810.4 5902.1 2992.7 3891.9 3901.7 3929.3 3930.5 4777.9 5049.5 5109.8 5340.8 5465.8 5046.6 5448.8 5456.8 5466.9 5470.6 4650.8 4885.8 4964.3 5358.9 5399.6 4534.5 5200.3 5245.5 5253.3 5258.8 4595.3 4853.5 5121.9 5212.8 5492.7 4988.3 5281.1 5322.4 5398.7 5410.2 17:19:18 INFO - PROCESS | 1953 | 17:19:18 INFO - PROCESS | 1953 | [#5] yui.html Cycles:180 Average:24272.22 Median:3761.73 stddev:37947.67 (1008.8%) stddev-sans-first:38012.91 17:19:18 INFO - PROCESS | 1953 | Values: 715.8 729.7 744.8 757.0 786.7 15379.4 15619.5 15634.5 15640.3 15672.1 13885.6 13931.2 13965.2 13967.7 13980.0 616.9 667.6 688.4 820.4 845.6 843.3 846.5 851.5 853.5 856.9 900.4 901.5 902.8 903.7 905.0 705.2 725.3 891.2 891.8 892.5 537.7 538.7 540.5 647.9 665.4 14842.6 14892.6 14914.6 14919.2 14929.6 13710.9 13927.5 13983.4 14005.2 14244.8 12310.0 12478.0 12648.5 12826.2 13150.5 4431.8 4476.5 4539.7 4647.2 4664.8 105870.0 110864.0 111980.4 112560.0 112774.4 10699.4 10924.4 10927.8 11196.9 11286.5 21944.6 22070.7 22076.8 22090.8 22185.6 7850.4 7861.9 7872.7 7905.1 8055.4 4215.7 4229.0 4268.6 4360.0 4421.3 3607.1 3751.4 3759.5 3761.7 3761.8 678.9 679.8 681.0 728.3 735.8 559.7 615.7 631.7 649.8 664.9 9208.5 9361.3 9459.8 9543.8 9544.3 544.4 607.4 618.6 638.3 692.1 4200.6 4556.4 4620.7 4636.6 4748.6 128756.0 129450.0 133648.7 141849.0 148173.0 121846.0 122494.8 123029.0 125008.0 130098.0 95417.5 95543.0 113957.0 113995.4 115701.0 8650.0 8939.6 9266.5 9783.4 9850.0 48579.0 54527.6 59177.2 64830.7 66329.0 86998.6 88559.2 90430.1 92107.0 93086.0 13175.4 13681.8 13711.4 13729.9 14175.3 14467.2 14808.5 14831.0 14877.7 15055.8 13508.5 13783.3 13787.2 13836.2 14283.7 13357.6 13576.8 13813.7 13976.2 14076.9 13388.7 13500.8 13501.9 14008.9 14169.9 10690.3 11236.6 11304.5 11512.4 11579.3 9919.0 10276.6 10289.4 11280.0 11613.0 17:19:18 INFO - PROCESS | 1953 | -------- Summary: end -------- 17:19:18 INFO - PROCESS | 1953 | 17:19:18 INFO - TEST-INFO | 1953: exit 0 17:19:18 INFO - TEST-OK | dromaeo_css | took 1118771ms 17:19:18 INFO - TEST-START | kraken 17:19:18 INFO - operating with platform_type : mac_ 17:19:18 INFO - Initialising browser for kraken test... 17:19:18 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmp6PrtvO/profile http://localhost:52043/getInfo.html) 17:19:21 INFO - PROCESS | 1956 | __metrics Screen width/height:1600/1200 17:19:21 INFO - PROCESS | 1956 | colorDepth:24 17:19:21 INFO - PROCESS | 1956 | Browser inner width/height: 1024/689 17:19:21 INFO - PROCESS | 1956 | __metrics 17:19:21 INFO - PROCESS | 1956 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:19:21 INFO - PROCESS | 1956 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:19:21 INFO - TEST-INFO | 1956: exit 0 17:19:21 INFO - Browser initialized. 17:19:21 INFO - Running cycle 1/1 for kraken test... 17:19:21 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/tmp6PrtvO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:19:33 INFO - PROCESS | 1957 | RSS: Main: 235225088 17:19:33 INFO - PROCESS | 1957 | 17:19:36 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52043/tests/kraken/driver.html?testName=audio-beat-detection) 17:19:37 INFO - PROCESS | 1957 | RSS: Main: 294100992 17:19:37 INFO - PROCESS | 1957 | 17:19:40 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52043/tests/kraken/driver.html?testName=audio-dft) 17:19:40 INFO - PROCESS | 1957 | RSS: Main: 294039552 17:19:40 INFO - PROCESS | 1957 | 17:19:44 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52043/tests/kraken/driver.html?testName=audio-fft) 17:19:44 INFO - PROCESS | 1957 | RSS: Main: 384483328 17:19:44 INFO - PROCESS | 1957 | 17:19:47 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52043/tests/kraken/driver.html?testName=audio-oscillator) 17:19:47 INFO - PROCESS | 1957 | RSS: Main: 285691904 17:19:47 INFO - PROCESS | 1957 | 17:19:49 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52043/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:19:50 INFO - PROCESS | 1957 | RSS: Main: 289972224 17:19:50 INFO - PROCESS | 1957 | 17:19:52 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52043/tests/kraken/driver.html?testName=imaging-darkroom) 17:19:53 INFO - PROCESS | 1957 | RSS: Main: 327413760 17:19:53 INFO - PROCESS | 1957 | 17:19:56 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52043/tests/kraken/driver.html?testName=imaging-desaturate) 17:19:57 INFO - PROCESS | 1957 | RSS: Main: 328118272 17:19:57 INFO - PROCESS | 1957 | 17:19:59 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52043/tests/kraken/driver.html?testName=json-parse-financial) 17:20:00 INFO - PROCESS | 1957 | RSS: Main: 326238208 17:20:00 INFO - PROCESS | 1957 | 17:20:01 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52043/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:20:02 INFO - PROCESS | 1957 | RSS: Main: 301211648 17:20:02 INFO - PROCESS | 1957 | 17:20:04 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52043/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:20:05 INFO - PROCESS | 1957 | RSS: Main: 306614272 17:20:05 INFO - PROCESS | 1957 | 17:20:07 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52043/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:20:07 INFO - PROCESS | 1957 | RSS: Main: 309039104 17:20:07 INFO - PROCESS | 1957 | 17:20:10 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52043/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:20:11 INFO - PROCESS | 1957 | RSS: Main: 317882368 17:20:11 INFO - PROCESS | 1957 | 17:20:14 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52043/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:20:15 INFO - PROCESS | 1957 | RSS: Main: 317440000 17:20:15 INFO - PROCESS | 1957 | 17:20:16 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:52043/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52043/tests/kraken/driver.html?testName=ai-astar) 17:20:17 INFO - PROCESS | 1957 | RSS: Main: 361443328 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | __start_tp_report 17:20:17 INFO - PROCESS | 1957 | _x_x_mozilla_page_load 17:20:17 INFO - PROCESS | 1957 | _x_x_mozilla_page_load_details 17:20:17 INFO - PROCESS | 1957 | |i|pagename|runs| 17:20:17 INFO - PROCESS | 1957 | |0;ai-astar;112;113;112;112;114;113;112;114;108;112 17:20:17 INFO - PROCESS | 1957 | |1;audio-beat-detection;168;144;114;150;114;119;142;142;146;113 17:20:17 INFO - PROCESS | 1957 | |2;audio-dft;184;182;182;184;185;180;182;181;182;182 17:20:17 INFO - PROCESS | 1957 | |3;audio-fft;76;58;58;88;52;71;71;72;107;80 17:20:17 INFO - PROCESS | 1957 | |4;audio-oscillator;84;69;92;69;68;70;92;67;67;89 17:20:17 INFO - PROCESS | 1957 | |5;imaging-gaussian-blur;87;86;88;87;134;83;88;83;87;84 17:20:17 INFO - PROCESS | 1957 | |6;imaging-darkroom;96;95;104;95;95;97;96;95;95;96 17:20:17 INFO - PROCESS | 1957 | |7;imaging-desaturate;87;83;86;82;84;86;87;86;86;87 17:20:17 INFO - PROCESS | 1957 | |8;json-parse-financial;57;53;54;54;54;55;54;55;53;54 17:20:17 INFO - PROCESS | 1957 | |9;json-stringify-tinderbox;71;68;64;79;76;72;69;70;69;72 17:20:17 INFO - PROCESS | 1957 | |10;stanford-crypto-aes;91;85;89;88;86;85;84;84;85;86 17:20:17 INFO - PROCESS | 1957 | |11;stanford-crypto-ccm;145;145;143;140;143;146;146;142;177;151 17:20:17 INFO - PROCESS | 1957 | |12;stanford-crypto-pbkdf2;172;168;171;172;171;169;171;170;171;170 17:20:17 INFO - PROCESS | 1957 | |13;stanford-crypto-sha256-iterative;69;69;69;69;66;69;67;68;67;68 17:20:17 INFO - PROCESS | 1957 | __end_tp_report 17:20:17 INFO - PROCESS | 1957 | __start_cc_report 17:20:17 INFO - PROCESS | 1957 | _x_x_mozilla_cycle_collect,2049 17:20:17 INFO - PROCESS | 1957 | __end_cc_report 17:20:17 INFO - PROCESS | 1957 | __startTimestamp1476318017079__endTimestamp 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | ------- Summary: start ------- 17:20:17 INFO - PROCESS | 1957 | Number of tests: 14 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.50 stddev:1.69 (1.5%) stddev-sans-first:1.79 17:20:17 INFO - PROCESS | 1957 | Values: 112.0 113.0 112.0 112.0 114.0 113.0 112.0 114.0 108.0 112.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:143.00 stddev:18.96 (13.3%) stddev-sans-first:15.97 17:20:17 INFO - PROCESS | 1957 | Values: 168.0 144.0 114.0 150.0 114.0 119.0 142.0 142.0 146.0 113.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#2] audio-dft Cycles:10 Average:182.40 Median:182.00 stddev:1.51 (0.8%) stddev-sans-first:1.48 17:20:17 INFO - PROCESS | 1957 | Values: 184.0 182.0 182.0 184.0 185.0 180.0 182.0 181.0 182.0 182.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#3] audio-fft Cycles:10 Average:73.30 Median:71.50 stddev:16.12 (22.5%) stddev-sans-first:17.07 17:20:17 INFO - PROCESS | 1957 | Values: 76.0 58.0 58.0 88.0 52.0 71.0 71.0 72.0 107.0 80.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:77.00 stddev:11.06 (14.4%) stddev-sans-first:11.41 17:20:17 INFO - PROCESS | 1957 | Values: 84.0 69.0 92.0 69.0 68.0 70.0 92.0 67.0 67.0 89.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:87.00 stddev:15.33 (17.6%) stddev-sans-first:16.21 17:20:17 INFO - PROCESS | 1957 | Values: 87.0 86.0 88.0 87.0 134.0 83.0 88.0 83.0 87.0 84.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:95.50 stddev:2.76 (2.9%) stddev-sans-first:2.92 17:20:17 INFO - PROCESS | 1957 | Values: 96.0 95.0 104.0 95.0 95.0 97.0 96.0 95.0 95.0 96.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:1.78 (2.1%) stddev-sans-first:1.79 17:20:17 INFO - PROCESS | 1957 | Values: 87.0 83.0 86.0 82.0 84.0 86.0 87.0 86.0 86.0 87.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:1.16 (2.1%) stddev-sans-first:0.71 17:20:17 INFO - PROCESS | 1957 | Values: 57.0 53.0 54.0 54.0 54.0 55.0 54.0 55.0 53.0 54.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.50 stddev:4.19 (5.9%) stddev-sans-first:4.44 17:20:17 INFO - PROCESS | 1957 | Values: 71.0 68.0 64.0 79.0 76.0 72.0 69.0 70.0 69.0 72.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:86.00 stddev:2.31 (2.7%) stddev-sans-first:1.72 17:20:17 INFO - PROCESS | 1957 | Values: 91.0 85.0 89.0 88.0 86.0 85.0 84.0 84.0 85.0 86.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:145.50 stddev:10.67 (7.3%) stddev-sans-first:11.27 17:20:17 INFO - PROCESS | 1957 | Values: 145.0 145.0 143.0 140.0 143.0 146.0 146.0 142.0 177.0 151.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:171.00 stddev:1.27 (0.7%) stddev-sans-first:1.22 17:20:17 INFO - PROCESS | 1957 | Values: 172.0 168.0 171.0 172.0 171.0 169.0 171.0 170.0 171.0 170.0 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - PROCESS | 1957 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:69.00 stddev:1.10 (1.6%) stddev-sans-first:1.12 17:20:17 INFO - PROCESS | 1957 | Values: 69.0 69.0 69.0 69.0 66.0 69.0 67.0 68.0 67.0 68.0 17:20:17 INFO - PROCESS | 1957 | -------- Summary: end -------- 17:20:17 INFO - PROCESS | 1957 | 17:20:17 INFO - TEST-INFO | 1957: exit 0 17:20:17 INFO - TEST-OK | kraken | took 59039ms 17:20:17 INFO - SUITE-END | took 1177s 17:20:18 INFO - Completed test suite (00:19:38) 17:20:18 INFO - javascript benchmark 17:20:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.6779752503953, 3158.778885478016, 3271.721903638191, 3289.0461766087838, 3306.5370848081257, 5424.430434804348, 5544.974179776744, 5554.1391084381885, 5628.099504079343, 5676.120201368789, 5549.0, 5560.582956278275, 5652.236948012028, 5666.0, 5699.088145896647, 5038.722870242135, 5066.873328166788, 5074.949250507503, 5121.257417674435, 5162.303089082963, 5190.169572868342, 5247.002493369237, 5252.212168174785, 5255.632105752599, 5259.947400526004, 4365.388845561624, 5195.558377537914, 5227.163653815409, 5232.16285394337, 5246.134387826023, 3592.784432934006, 5078.263651770472, 5098.362704661918, 5151.896962060738, 5162.819301324474, 5057.468965758616, 5103.744812759384, 5105.642605017651, 5123.692578445305, 5133.460986596391, 4623.422072240936, 5566.387697353288, 5594.636348637367, 5616.831495055155, 5617.8033768817895, 6097.040762675677, 6099.097936865534, 6100.732564372323, 6101.29763069421, 6124.7243874025335, 6125.847626645375, 6132.63086471492, 6155.815325540241, 6178.814635560941, 6188.350223226541, 5607.4112218217315, 5647.6893770843, 5650.152477128464, 5776.046952252873, 5776.075827867563, 6293.748250069946, 6333.429991300805, 6334.208223972004, 6339.270983836917, 6342.175517182829, 5886.470217680429, 6113.908291375633, 6116.908246376308, 6118.969405152946, 6120.357362476876, 6103.176071230416, 6133.172021777093, 6135.7291609442955, 6136.8772624547255, 6143.634700640197, 6147.538934579924, 6159.6612186330185, 6166.229221347332, 6176.7838125666285, 6179.412955769195, 6169.814905552751, 6175.96912015446, 6181.907271390933, 6207.503399728011, 6209.534284928648, 4765.880018195721, 4816.470188279286, 4822.42130944289, 4845.442774080955, 4887.902241955212, 4389.3415987601475, 4396.208682437128, 4414.492333381638, 4417.249067658506, 4460.933086003713, 5648.350439699487, 5658.688772117491, 5661.1508273758445, 5667.1499275108235, 5672.14917762339, 6093.969530152321, 6132.080177995925, 6140.289530736458, 6181.660008699475, 6261.092141639437, 5600.747966341524, 5613.382527921925, 5633.887322253532, 5685.948099601587, 5746.913796293059, 5379.677219366772, 5382.542483888913, 5382.650127741685, 5387.218853266255, 5390.946090539122, 6206.472449841814, 6310.9368906311265, 6324.588901721373, 6344.333844946399, 6344.429001389989, 6037.45662890342, 6136.978641397139, 6167.5682702210415, 6175.0, 6187.102870083902, 6255.24937007562, 6289.874202516015, 6310.968445157929, 6317.210348706411, 6322.652254126067, 6113.663748493876, 6118.816435506942, 6155.846103847261, 6167.722452489559, 6175.6912154393, 6076.0, 6125.234345706786, 6252.968260237192, 6258.12386265914, 6284.591501552384, 5891.23413956183, 5932.347441781487, 5993.67034813072, 6065.909011364744, 6097.695115244309, 5858.238429004202, 5992.221011268507, 6002.87994240104, 6015.578909476295, 6016.548758843017, 5663.490285874291, 5806.303243610793, 5807.419258074327, 5851.385604511635, 5876.559258055749, 5784.248047753765, 5955.970220148872, 5970.223870896929, 5996.220491336098, 6116.113163591343, 5679.886402271849, 5861.706914654335, 5866.0, 5874.676892770939, 5904.557158213237], "value": 5613.911354048708, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.7944425008746, 483.22924934729093, 523.2726510150891, 523.9004589128066, 550.0786183143234, 30634.08097757068, 38678.03304917375, 39945.60054399455, 40004.79997600008, 40190.7990460048, 41224.0, 41534.792326038405, 41667.58332416675, 41678.16643667125, 41680.332786688494, 9446.149846513812, 9582.425054496718, 9617.42295462271, 9618.51907404627, 9626.278029147807, 9411.0, 9490.81018379632, 9496.0, 9509.667161649344, 9516.762080947963, 9466.337356385056, 9519.714408567754, 9530.80938381232, 9547.522623868814, 9576.090271424238, 9483.525823708822, 9523.904760952371, 9531.618735250617, 9534.809303813885, 9560.139587437165, 9447.52762361886, 9539.52302384878, 9545.665901693408, 9546.809063818755, 9553.474558899257, 39005.0, 39292.60707392918, 39305.0, 39315.606843931484, 39355.40966885499, 3150.3069324748517, 3184.3472088222093, 3349.676877633321, 3591.8742844000335, 3868.207017561393, 29620.111396658136, 30323.393532129456, 30845.0, 30898.0, 31127.754889804382, 3148.370325934822, 3151.936961260762, 3161.146490447566, 3174.761892858045, 3185.3470038642254, 372701.0, 377026.1148694239, 377819.0, 377961.0, 378812.0, 55232.17151742727, 55350.0, 55365.0, 55486.72256638691, 55542.16686749702, 31426.685733142374, 31860.362792744014, 58112.41887581155, 58187.0, 58248.0, 12341.75316493665, 15994.680106397807, 16011.0, 16034.0, 16049.358025679076, 12295.508179672714, 12448.128630995925, 12453.1905426147, 12461.75076498465, 12465.189762665397, 17934.372296969545, 17938.013409262618, 17978.910105449388, 18547.846866032807, 18559.44321670352, 6356.872862542723, 7695.884561731578, 7732.226777322223, 7807.258310460497, 7849.686012559548, 857.5412154497315, 948.2129832239225, 955.507913424587, 1001.4642166441065, 1088.8257878739523, 28437.289067773472, 29144.0, 29303.706962930097, 30057.0, 30114.69885301119, 838.4047326398204, 1048.2714513413257, 1061.3843970497094, 1103.227740581596, 1130.9377984210948, 14453.710925781426, 14899.0, 14953.102813831205, 15254.0, 15279.923600381928, 626674.0, 938542.0, 947048.0, 947139.0, 947554.0, 45746.0, 46335.768321158175, 46341.30488042682, 46410.535894641296, 46419.07161856744, 1207.7463732616072, 1251.8247445357658, 1253.59258241072, 1333.9399727012308, 1359.3814814259488, 28665.42669146647, 29945.0, 30457.54313685252, 31447.685523144475, 31999.840000799846, 46198.07603847837, 52346.73826630843, 52842.0, 52905.47094529006, 53020.0, 528994.0, 531258.0, 531493.0, 531860.0, 532211.0, 26141.0850620235, 26339.73660263373, 26620.201393958214, 26729.0, 26756.464870702086, 44943.55056449393, 45418.0, 45552.316715248635, 46140.53859461362, 46180.07639847251, 30498.84750576233, 36201.0, 37661.81169094137, 38585.421218682306, 38715.41926871043, 36527.08682282964, 37114.628853712195, 37182.0, 37934.24131517409, 38310.42534362042, 31322.686773131976, 31598.684013160495, 32090.0, 34401.82799086089, 37196.0, 30001.249968750955, 32794.50808237926, 32873.67126328706, 32894.50658240176, 33087.83456082704, 24552.0, 24556.754432455447, 24722.876385617958, 25180.866860990933, 25634.743652563237], "value": 19268.686613554197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.36445948509095, 371.715637537284, 380.62508429197237, 388.974716643418, 399.65030598226554, 18534.629307413845, 19868.40394788155, 20183.293584724503, 20647.896760516178, 20859.37421877346, 20377.898110509428, 20654.69017964728, 20770.376888693325, 20977.16091356344, 21268.0, 5864.912026319608, 5984.192134061889, 5993.520518358521, 6027.457528822405, 6177.166082578839, 5873.1190321451695, 6013.669248191348, 6014.8797024059495, 6023.0, 6051.969740151293, 5679.971600142014, 5928.110783382484, 5961.284645842505, 5982.880342393171, 6001.189839371673, 5828.0, 5929.673867937283, 5985.401459854002, 5987.491063259628, 6006.249218847644, 5144.382674079135, 5194.428612852583, 5208.322918020633, 5224.582033437316, 5225.451327610622, 19629.0, 25858.094966676264, 25896.50397196373, 25900.96396144152, 26215.34461638455, 2178.5098352870637, 2221.677856710768, 2365.207655435431, 2368.810495160383, 2429.7327293997646, 15342.693146137015, 15532.534023979299, 15584.844151558565, 15929.442469513628, 16246.268917898722, 1836.5867679772077, 1843.4653950354382, 1867.794542600313, 1927.2194761121768, 1927.8457723382237, 116786.83213167844, 117396.82603173945, 117445.0, 117459.23811142839, 119676.0, 10310.278280520291, 10543.630972915911, 10574.101201397967, 10835.891641083646, 11043.889561104446, 11288.661340159808, 13554.118982266122, 13563.660908477366, 13585.864141358657, 13604.659883502794, 7845.333146682482, 8081.515109093473, 8142.796430089295, 8219.753407397668, 8258.0, 7874.409419293462, 7874.685012599546, 7922.405819563556, 8050.597470126588, 8055.7583272501915, 5807.12893066035, 6062.90905636407, 6423.940049891809, 6564.442022428051, 6676.96661516699, 1539.9153046582546, 1824.2976454065015, 1826.7259911013455, 1826.963460730778, 1830.9633807324046, 902.8781114549452, 990.9752256193509, 1005.7284533176002, 1018.1854516387009, 1074.7420619051372, 21540.892295538422, 22272.55454890893, 22273.66589501159, 22280.99735511906, 22349.553008940056, 933.8179055084329, 937.4984383355009, 946.3659348236698, 1008.2690049713899, 1047.528612124547, 10903.909609039032, 11464.885351146548, 11594.94202528982, 11697.590584329508, 11711.355875426763, 509207.0, 565746.5414056295, 576482.0, 581945.0, 619583.0, 82533.0, 103670.48164759128, 112606.0, 115956.0, 116311.0, 953.1231267234161, 975.2685485885586, 1017.8473229015707, 1018.0735530667057, 1019.1439191079529, 13113.803292950422, 19524.121414536665, 19923.701144482555, 20048.799512004694, 20432.48918776983, 40569.17832490627, 54230.45769542254, 55439.0, 55591.722041389534, 55907.44092559022, 79103.76132296243, 80314.0, 80782.0, 81281.78077328963, 82550.0, 7722.3275776026, 7739.0, 7747.30274275318, 8182.959085204536, 8285.295749861329, 11109.833467486122, 12372.876271237172, 13002.219866808022, 13018.088733788543, 13088.0838341319, 10755.750273822689, 11170.994610485095, 11209.99110080064, 11296.774064518828, 11395.259308145276, 10883.945580272048, 10985.505652245509, 11209.215354925076, 11301.547938082385, 11359.977602015859, 8925.464472131693, 11087.0, 11165.609203677832, 11394.94302528482, 11440.313581185155, 7802.492837965741, 7900.604969751605, 7933.8413231736195, 7978.960105199437, 8040.2361775631225, 8667.306615470874, 10884.61903833362, 11085.390303533382, 11088.44557772092, 11117.055050320814], "value": 9650.54696615942, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.3076815440045, 1387.07066265602, 1451.1728314860532, 1596.6647004129131, 1900.4108726294849, 7775.455718099728, 8236.764485327203, 8613.353998450119, 8879.822403551942, 8885.289176865852, 8733.91266087339, 8800.163984421486, 8811.823763524726, 8815.955920220405, 8816.691415800447, 2324.976750232497, 2353.482233908546, 2358.870262135582, 2359.1507057459385, 2366.0890557135453, 2313.791758741713, 2341.94145146371, 2361.8700971446565, 2362.2795047510504, 2362.2795047510504, 2308.7922087012166, 2327.7439481657, 2330.8018818400456, 2347.5187586544803, 2347.6126439137606, 2299.367673889677, 2322.5703244899746, 2326.4532834783813, 2329.266281121458, 2333.929982100523, 2308.6421604651305, 2315.583195024895, 2318.420394901275, 2323.802476789458, 2326.6626339180893, 8774.912250877473, 8843.982941961693, 8863.955680221623, 8875.979262384779, 8877.911220887772, 5168.250603662485, 5202.635815492917, 5381.623286369956, 5386.973065134688, 5399.217113518518, 3444.6383129771266, 3608.675219230282, 3638.43604241343, 3647.6352364763575, 3648.4892115103908, 1047.204124865108, 1050.0916707048425, 1064.8030114428855, 1065.712257690427, 1074.1621535202555, 11565.653030409017, 11780.175387722862, 11794.233374830608, 11810.468528916219, 11834.585789497327, 4451.554844515523, 4479.888002799956, 4652.651051171176, 4671.019085991979, 4678.345031695565, 8302.252797248277, 9106.86339704905, 9120.58957346921, 9133.77165570866, 9141.817163656822, 5002.57478114357, 5282.920756188661, 5289.629725919149, 5292.8147514837565, 5293.735313234323, 3963.4847469829315, 4115.52671442782, 4403.581659742319, 4592.678512504092, 4919.631027672939, 3753.3994560870674, 3766.4350347448103, 3825.617438256152, 3828.4831547741255, 3837.9040523986564, 1118.6811758648746, 1127.3799410324452, 1132.9716757080973, 1139.8062329403856, 1153.8615366155948, 826.995865020683, 843.4328113266715, 896.5427631907774, 906.6237511432813, 928.9581968811419, 5617.325920889438, 5766.596338256281, 5786.04530252512, 5925.200097986803, 5959.165716799652, 768.6617888129206, 914.9432405571575, 931.4271722890373, 938.5260443476001, 1004.4375149915925, 3831.272058308912, 3836.693064554829, 3862.8454861805776, 3971.225611005884, 3998.660113890352, 10496.160307175407, 10869.40218287986, 10908.727281818017, 10930.289531180448, 10934.343939363664, 10777.299475534066, 10865.967733065347, 10867.130629549618, 10867.565297388015, 10871.83692244617, 7740.187280335484, 8145.0, 8159.388045896581, 8169.223923727236, 8316.251537361666, 5896.528277737856, 5934.406559344031, 6055.2430946131735, 6084.969575152273, 6159.4456498915315, 10077.294589378673, 10201.693949181536, 10241.897581024094, 10248.38509689421, 10261.794764104825, 8832.867506987528, 9879.160271377013, 10198.541065651914, 10208.132308753839, 10390.896091039296, 5504.4770746778995, 7523.247675232433, 7536.0, 7585.582792946441, 7587.393008559412, 8252.917470825216, 8308.210719981593, 8336.124706905688, 8396.244338009608, 8443.282321002784, 7509.774706758806, 7550.622468876424, 7591.696332146652, 7592.772216833504, 7639.694412223671, 7072.858542829012, 7294.927050729425, 7299.5620262784405, 7440.479166458296, 7557.206493318123, 6741.359570840762, 6990.580565166107, 7108.573485590881, 7131.25121862197, 7131.893021604784, 4857.732824694675, 4953.455119936876, 4955.529224723646, 5020.899582008266, 5153.793848246178, 5005.449400565862, 5057.898842023213, 5061.646334454349, 5085.694858308513, 5188.273641690091], "value": 4532.946094411964, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.5189225078249, 303.8131549097305, 312.8560862003478, 317.6997737138405, 319.65477284532705, 5303.011370735974, 5333.4399888011685, 5363.0, 5401.27082843817, 5425.5117039466395, 4166.9374959375555, 4239.9576004240025, 5073.7209453480045, 5233.1103712368895, 5326.0587165497545, 4030.375291829771, 4042.2319759245797, 4048.781218930957, 4059.776712280823, 4083.6120568546016, 3271.8854840080603, 3981.920361592781, 4039.4950631171105, 4055.9797201013953, 4077.531083925342, 3285.0, 3299.389612921605, 3882.1559759114475, 3994.3009973254566, 4046.534648515414, 4044.705694177864, 4050.3721923101966, 4069.4506241657296, 4085.754854708727, 4103.199876024177, 2901.8984335548153, 3508.824558772051, 3518.8064656443885, 3523.5243242162237, 3529.894103176883, 4071.837126514936, 5109.897802043938, 5134.332536770234, 5192.961407718433, 5210.114280572274, 4517.819287228507, 4530.0, 4569.405977222939, 4582.5417458253905, 4591.908161836778, 4322.546132656057, 5576.498115169654, 5592.412796656374, 5594.496495315441, 5622.381538030813, 3363.6131844837914, 3796.962030379716, 3809.552370099372, 3821.025638462202, 3836.1943991761764, 5547.338756420783, 5831.912521312183, 5871.295444546639, 5877.324107727581, 5916.3023110555905, 3833.0, 3842.538895332578, 3848.5958974307296, 3858.749181303206, 3868.1103346230525, 3910.7262491625884, 3929.1945151243926, 3929.3713005918917, 3948.3287841067086, 3958.5447673517338, 3431.2294331593043, 4368.912621747547, 4479.7984090715345, 4527.61515271199, 4652.6975746577045, 5577.581681373913, 5592.217089607459, 5593.20339830085, 5596.916046259309, 5628.409017053151, 1212.7392610588765, 1249.6563445052684, 1302.446460254388, 1311.560627189902, 1333.7332533493145, 635.3360738028771, 647.4043879630751, 771.124773382204, 775.8874963130428, 783.2246076384339, 4301.19349873054, 4803.423589169252, 4837.854864354075, 5247.55395791362, 5287.9735601321745, 4727.5745182933015, 5583.771570254537, 5734.541236701054, 5753.015209752629, 5766.855828604318, 4487.596116349544, 4615.469221039556, 4917.87705307363, 5116.334876466036, 5229.4770522948165, 2597.532444160069, 2963.866626001835, 2966.2287805170813, 2970.420767950272, 2978.955315670267, 4968.90062198754, 5551.7799676187715, 5803.8549036274435, 5908.379620139909, 5933.465988061095, 5717.685527296038, 5735.8852822944145, 5762.88804045819, 5763.723329284909, 5841.7371218295275, 5044.848654540297, 5846.649201047951, 5850.912236316459, 5873.2658417697785, 5884.382139875337, 5272.6309158358545, 5557.527610153183, 5648.491635752802, 5671.489565939085, 5723.0, 5572.1303528683575, 5586.748596313257, 5594.300712410949, 5602.159676048625, 5616.606837521498, 5089.796408143633, 5396.790503407192, 5785.8467135410465, 5810.414365607264, 5902.114682797443, 2992.730654241129, 3891.9297193272077, 3901.7463864848696, 3929.2534418461546, 3930.469386632768, 4777.948851252719, 5049.495050494921, 5109.821156259513, 5340.839774806762, 5465.824847657773, 5046.59627229828, 5448.83653490396, 5456.809011684572, 5466.945330546644, 5470.617056805985, 4650.793808196932, 4885.828995985124, 4964.304997300309, 5358.866028349323, 5399.625234228607, 4534.460702006887, 5200.323957885451, 5245.501677340647, 5253.343332083489, 5258.842234732964, 4595.333676616805, 4853.51464853526, 5121.925620123856, 5212.791488340576, 5492.725363731877, 4988.301637770643, 5281.069006701317, 5322.441143679858, 5398.703071331114, 5410.161424979135], "value": 4006.0636317365133, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.8460930899859, 729.7409419656022, 744.7505085796257, 756.9629607437811, 786.665667091486, 15379.384824607016, 15619.453319133832, 15634.452794152206, 15640.296186671612, 15672.138032408227, 13885.583432497042, 13931.164130152198, 13965.231912244848, 13967.720645587107, 13980.02139850211, 616.8797084568511, 667.5727534378002, 688.3838964127103, 820.3560205238889, 845.564534264854, 843.2705709561246, 846.496334680867, 851.4976164063202, 853.4665833853842, 856.9143085691443, 900.3967341880923, 901.5086777706133, 902.7697937026049, 903.6972614174226, 904.9592768325441, 705.2348202200618, 725.2892165677665, 891.2023738753811, 891.7725979875123, 892.4689809563299, 537.6693333599808, 538.6902531044665, 540.4568408749221, 647.9190101237346, 665.431056446736, 14842.554723358317, 14892.553223403207, 14914.552563423114, 14919.179445130514, 14929.552113436614, 13710.931445342809, 13927.512537061255, 13983.37074831635, 14005.229712365815, 14244.786328205086, 12310.0, 12478.00175985919, 12648.49406023758, 12826.166299190523, 13150.539731109366, 4431.822727090913, 4476.485204201526, 4539.7049191802425, 4647.209974304343, 4664.766761661904, 105870.0, 110864.0, 111980.44009779899, 112560.0, 112774.43612781966, 10699.358038517714, 10924.399158046224, 10927.781444371069, 11196.888031119584, 11286.492107855167, 21944.561108777736, 22070.66893996591, 22076.779232207795, 22090.779092209195, 22185.556288874133, 7850.411219158586, 7861.882071768814, 7872.724454644174, 7905.051393832777, 8055.436119471581, 4215.683823713233, 4229.0, 4268.615824575803, 4359.956400435955, 4421.314696222091, 3607.1162565171703, 3751.4185301278344, 3759.548854137466, 3761.6614504694708, 3761.7931013793955, 678.9117414736053, 679.7824696097201, 680.9829754256084, 728.300831202052, 735.8234023834349, 559.7089513453063, 615.6829232945037, 631.7062565906783, 649.8277956341574, 664.9261442769891, 9208.539573021322, 9361.297902657192, 9459.763505912408, 9543.809123817486, 9544.291851888616, 544.371251204859, 607.4290167242777, 618.5731845026917, 638.3488841381765, 692.1348314606741, 4200.558941311179, 4556.407667003268, 4620.699654522446, 4636.5595268450115, 4748.643851711066, 128756.0, 129450.0, 133648.66351336363, 141849.0, 148173.0, 121846.0, 122494.77505225012, 123029.0, 125008.0, 130098.0, 95417.52291238638, 95543.0, 113957.0, 113995.43002285101, 115701.01680680644, 8650.0, 8939.642414303355, 9266.490343030928, 9783.41299522031, 9849.95075024649, 48579.0, 54527.63876876571, 59177.22468325957, 64830.67584662046, 66329.00506492503, 86998.5650071771, 88559.22881542204, 90430.0956990448, 92107.0, 93086.0, 13175.407106680414, 13681.79477307861, 13711.382987765774, 13729.862701372858, 14175.298804780876, 14467.204303763394, 14808.481703140338, 14830.995631179738, 14877.702445951238, 15055.849441505883, 13508.459661613426, 13783.310834458438, 13787.241701706102, 13836.169829810244, 14283.714325713636, 13357.599272021855, 13576.79634805459, 13813.723725525635, 13976.161430313807, 14076.929615351857, 13388.665283367603, 13500.797488037884, 13501.864981350061, 14008.859911401163, 14169.929150354183, 10690.343533057086, 11236.606718764804, 11304.491297891778, 11512.36681982499, 11579.278869465837, 9918.950405247928, 10276.640317588848, 10289.382637041801, 11280.0, 11613.0], "value": 7781.760022988897, "unit": "score"}], "name": "dromaeo_css", "value": 7269.123456810638, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 112.0, 112.0, 114.0, 113.0, 112.0, 114.0, 108.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 144.0, 114.0, 150.0, 114.0, 119.0, 142.0, 142.0, 146.0, 113.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 182.0, 184.0, 185.0, 180.0, 182.0, 181.0, 182.0, 182.0], "value": 182.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 58.0, 88.0, 52.0, 71.0, 71.0, 72.0, 107.0, 80.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 92.0, 69.0, 68.0, 70.0, 92.0, 67.0, 67.0, 89.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 87.0, 134.0, 83.0, 88.0, 83.0, 87.0, 84.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 104.0, 95.0, 95.0, 97.0, 96.0, 95.0, 95.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 82.0, 84.0, 86.0, 87.0, 86.0, 86.0, 87.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 54.0, 54.0, 54.0, 55.0, 54.0, 55.0, 53.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 64.0, 79.0, 76.0, 72.0, 69.0, 70.0, 69.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 89.0, 88.0, 86.0, 85.0, 84.0, 84.0, 85.0, 86.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 143.0, 140.0, 143.0, 146.0, 146.0, 142.0, 177.0, 151.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 168.0, 171.0, 172.0, 171.0, 169.0, 171.0, 170.0, 171.0, 170.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 69.0, 66.0, 69.0, 67.0, 68.0, 67.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1450.2999999999997, "alertThreshold": 2.0}]} 17:20:18 INFO - Return code: 0 17:20:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:20:18 INFO - # TBPL SUCCESS # 17:20:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:20:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:20:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:20:18 INFO - 2016-10-12 17:20:18.382 screenresolution[1961:18463] starting screenresolution argv=screenresolution get 17:20:18 INFO - 2016-10-12 17:20:18.385 screenresolution[1961:18463] Display 0: 1600x1200x32@60 17:20:18 INFO - 2016-10-12 17:20:18.391 screenresolution[1962:18466] starting screenresolution argv=screenresolution list 17:20:18 INFO - Available Modes on Display 0 17:20:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:20:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:20:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:20:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:20:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:20:18 INFO - Intel Iris: 17:20:18 INFO - Chipset Model: Intel Iris 17:20:18 INFO - Type: GPU 17:20:18 INFO - Bus: Built-In 17:20:18 INFO - VRAM (Dynamic, Max): 1536 MB 17:20:18 INFO - Vendor: Intel (0x8086) 17:20:18 INFO - Device ID: 0x0a2e 17:20:18 INFO - Revision ID: 0x0009 17:20:18 INFO - Displays: 17:20:18 INFO - Display: 17:20:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:20:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:20:18 INFO - Display Serial Number: 1600x1200 60 17:20:18 INFO - Main Display: Yes 17:20:18 INFO - Mirror: Off 17:20:18 INFO - Online: Yes 17:20:18 INFO - Rotation: Supported 17:20:18 INFO - Return code: 0 17:20:18 INFO - Running post-action listener: _resource_record_post_action 17:20:18 INFO - [mozharness: 2016-10-13 00:20:18.633032Z] Finished run-tests step (success) 17:20:18 INFO - Running post-run listener: _resource_record_post_run 17:20:18 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 569344; Write bytes: 707380224; Read time: 5; Write time: 1985 17:20:18 INFO - TinderboxPrint: CPU usage
53.5% 17:20:18 INFO - TinderboxPrint: I/O read bytes / time
569,344 / 5 17:20:18 INFO - TinderboxPrint: I/O write bytes / time
707,380,224 / 1,985 17:20:18 INFO - TinderboxPrint: CPU idle
2,257.1 (46.5%) 17:20:18 INFO - TinderboxPrint: CPU system
971.2 (20.0%) 17:20:18 INFO - TinderboxPrint: CPU user
1,625.4 (33.5%) 17:20:18 INFO - TinderboxPrint: Swap in / out
407,347,200 / 0 17:20:18 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407978496; Write bytes: 409794048; Read time: 30853; Write time: 852 17:20:18 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 503808; Write bytes: 295988736; Read time: 3; Write time: 1123 17:20:19 INFO - Running post-run listener: _upload_blobber_files 17:20:19 INFO - Blob upload gear active. 17:20:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:20:19 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 17:20:19 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'] 17:20:19 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 17:20:19 INFO - (blobuploader) - INFO - Open directory for files ... 17:20:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:20:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:20:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:20 INFO - (blobuploader) - INFO - Done attempting. 17:20:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:20:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:20:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:21 INFO - (blobuploader) - INFO - Done attempting. 17:20:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:20:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:20:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:20:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:20:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:20:23 INFO - (blobuploader) - INFO - Done attempting. 17:20:23 INFO - (blobuploader) - INFO - Iteration through files over. 17:20:23 INFO - Return code: 0 17:20:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:20:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:20:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b14b8c2a6602317b3053a6f0ccc8ef687c4ae9908b7df62e2bc2fd9ca600a9461535faa4a04b52cd303d3c02c0ba0b687de942dc289fde0c801c619ec5f6f4df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5b80075c0c10f5b6f3f0469ad3d688f797aa18dfe7290792761e7d53e434252b75733b9be33265da4f0b98a9ba228c71338abf3521060eb2d6b66f53273697f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6492196df897a3326acae3b15fc6134d88c166457267d63fcc6aa2bfe27c9a2c2d5eab0578e336adf0426191ac1cb93c3d28a28f623db0e20f078063189fdd63"} 17:20:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:20:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:20:23 INFO - Contents: 17:20:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b14b8c2a6602317b3053a6f0ccc8ef687c4ae9908b7df62e2bc2fd9ca600a9461535faa4a04b52cd303d3c02c0ba0b687de942dc289fde0c801c619ec5f6f4df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5b80075c0c10f5b6f3f0469ad3d688f797aa18dfe7290792761e7d53e434252b75733b9be33265da4f0b98a9ba228c71338abf3521060eb2d6b66f53273697f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6492196df897a3326acae3b15fc6134d88c166457267d63fcc6aa2bfe27c9a2c2d5eab0578e336adf0426191ac1cb93c3d28a28f623db0e20f078063189fdd63"} 17:20:23 INFO - Running post-run listener: copy_logs_to_upload_dir 17:20:23 INFO - Copying logs to upload dir... 17:20:23 INFO - mkdir: /builds/slave/test/build/upload/logs 17:20:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.598268 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-12 17:20:23.588871) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-10-12 17:20:23.596357) ========= 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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b14b8c2a6602317b3053a6f0ccc8ef687c4ae9908b7df62e2bc2fd9ca600a9461535faa4a04b52cd303d3c02c0ba0b687de942dc289fde0c801c619ec5f6f4df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5b80075c0c10f5b6f3f0469ad3d688f797aa18dfe7290792761e7d53e434252b75733b9be33265da4f0b98a9ba228c71338abf3521060eb2d6b66f53273697f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6492196df897a3326acae3b15fc6134d88c166457267d63fcc6aa2bfe27c9a2c2d5eab0578e336adf0426191ac1cb93c3d28a28f623db0e20f078063189fdd63"} 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.011174 build_url: 'https://queue.taskcluster.net/v1/task/BQTJeivmQQmEq-q291txxg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b14b8c2a6602317b3053a6f0ccc8ef687c4ae9908b7df62e2bc2fd9ca600a9461535faa4a04b52cd303d3c02c0ba0b687de942dc289fde0c801c619ec5f6f4df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d5b80075c0c10f5b6f3f0469ad3d688f797aa18dfe7290792761e7d53e434252b75733b9be33265da4f0b98a9ba228c71338abf3521060eb2d6b66f53273697f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6492196df897a3326acae3b15fc6134d88c166457267d63fcc6aa2bfe27c9a2c2d5eab0578e336adf0426191ac1cb93c3d28a28f623db0e20f078063189fdd63"}' ========= master_lag: 1.29 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-10-12 17:20:24.898106) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:20:24.898445) ========= 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.Tog6JK8tCp/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.XU0WtpDPWx/Listeners TMPDIR=/var/folders/b0/r5l_zcbd7q3gp8hj0t34qjvc00000w/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.005445 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 17:20:24.929431) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 17:20:24.929734) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 17:20:24.933058) ========= ========= Total master_lag: 1.44 =========