builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0160 starttime: 1468198622.59 results: success (0) buildid: 20160710161526 builduid: 1ed77665a647452ea159b502af30e6fe revision: fa7a42657c48de78ba1a0fefd43d1c6ed233e97a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:02.592492) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:02.593244) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:02.593534) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:02.673308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:02.673654) ========= 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.kI9pCHTT93/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.i0unTEexnC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-07-10 17:57:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.77M=0.001s 2016-07-10 17:57:02 (8.77 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129899 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:02.826552) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:02.826900) ========= 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.kI9pCHTT93/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.i0unTEexnC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.028066 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:02.876473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-10 17:57:02.876850) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fa7a42657c48de78ba1a0fefd43d1c6ed233e97a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fa7a42657c48de78ba1a0fefd43d1c6ed233e97a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kI9pCHTT93/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.i0unTEexnC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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-07-10 17:57:02,952 truncating revision to first 12 chars 2016-07-10 17:57:02,952 Setting DEBUG logging. 2016-07-10 17:57:02,952 attempt 1/10 2016-07-10 17:57:02,952 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fa7a42657c48?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-10 17:57:04,958 unpacking tar archive at: mozilla-beta-fa7a42657c48/testing/mozharness/ program finished with exit code 0 elapsedTime=2.263417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-10 17:57:05.164304) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:05.164629) ========= script_repo_revision: fa7a42657c48de78ba1a0fefd43d1c6ed233e97a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:05.165046) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:05.165319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 17:57:05.184321) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-07-10 17:57:05.184636) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kI9pCHTT93/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.i0unTEexnC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:57:05 INFO - MultiFileLogger online at 20160710 17:57:05 in /builds/slave/test 17:57:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:57:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:57:05 INFO - {'append_to_log': False, 17:57:05 INFO - 'base_work_dir': '/builds/slave/test', 17:57:05 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:57:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:57:05 INFO - 'branch': 'Mozilla-Beta', 17:57:05 INFO - 'buildbot_json_path': 'buildprops.json', 17:57:05 INFO - 'config_files': ('talos/mac_config.py',), 17:57:05 INFO - 'default_actions': ('clobber', 17:57:05 INFO - 'read-buildbot-config', 17:57:05 INFO - 'download-and-extract', 17:57:05 INFO - 'populate-webroot', 17:57:05 INFO - 'create-virtualenv', 17:57:05 INFO - 'install', 17:57:05 INFO - 'run-tests'), 17:57:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:57:05 INFO - 'download_minidump_stackwalk': True, 17:57:05 INFO - 'download_symbols': 'ondemand', 17:57:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:57:05 INFO - 'tooltool.py': '/tools/tooltool.py', 17:57:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:57:05 INFO - '/tools/misc-python/virtualenv.py')}, 17:57:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:57:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:57:05 INFO - 'installer_path': 'installer.exe', 17:57:05 INFO - 'log_level': 'info', 17:57:05 INFO - 'log_name': 'talos', 17:57:05 INFO - 'log_to_console': True, 17:57:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:57:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:57:05 INFO - 'opt_config_files': (), 17:57:05 INFO - 'pip_index': False, 17:57:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:57:05 INFO - 'cmd': ('bash', 17:57:05 INFO - '-c', 17:57:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:57:05 INFO - 'enabled': True, 17:57:05 INFO - 'halt_on_failure': False, 17:57:05 INFO - 'name': 'check_screen_resolution'},), 17:57:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:57:05 INFO - 'cmd': ('bash', 17:57:05 INFO - '-c', 17:57:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:57:05 INFO - 'enabled': True, 17:57:05 INFO - 'halt_on_failure': False, 17:57:05 INFO - 'name': 'check_screen_resolution'},), 17:57:05 INFO - 'run_cmd_checks_enabled': True, 17:57:05 INFO - 'sps_profile': False, 17:57:05 INFO - 'sps_profile_interval': 0, 17:57:05 INFO - 'suite': 'dromaeojs', 17:57:05 INFO - 'system_bits': '32', 17:57:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:57:05 INFO - 'title': 't-yosemite-r7-0160', 17:57:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:57:05 INFO - 'use_talos_json': True, 17:57:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:57:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:57:05 INFO - 'work_dir': 'build'} 17:57:05 INFO - ##### 17:57:05 INFO - ##### Running clobber step. 17:57:05 INFO - ##### 17:57:05 INFO - Running pre-action listener: _resource_record_pre_action 17:57:05 INFO - Running main action method: clobber 17:57:05 INFO - rmtree: /builds/slave/test/build 17:57:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:57:07 INFO - Running post-action listener: _resource_record_post_action 17:57:07 INFO - ##### 17:57:07 INFO - ##### Running read-buildbot-config step. 17:57:07 INFO - ##### 17:57:07 INFO - Running pre-action listener: _resource_record_pre_action 17:57:07 INFO - Running main action method: read_buildbot_config 17:57:07 INFO - Using buildbot properties: 17:57:07 INFO - { 17:57:07 INFO - "project": "", 17:57:07 INFO - "product": "firefox", 17:57:07 INFO - "script_repo_revision": "production", 17:57:07 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 17:57:07 INFO - "repository": "", 17:57:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 17:57:07 INFO - "buildid": "20160710161526", 17:57:07 INFO - "pgo_build": "False", 17:57:07 INFO - "basedir": "/builds/slave/test", 17:57:07 INFO - "buildnumber": 63, 17:57:07 INFO - "slavename": "t-yosemite-r7-0160", 17:57:07 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:57:07 INFO - "platform": "yosemite_r7", 17:57:07 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 17:57:07 INFO - "branch": "mozilla-beta", 17:57:07 INFO - "repo_path": "releases/mozilla-beta", 17:57:07 INFO - "revision": "fa7a42657c48de78ba1a0fefd43d1c6ed233e97a", 17:57:07 INFO - "stage_platform": "macosx64", 17:57:07 INFO - "builduid": "1ed77665a647452ea159b502af30e6fe", 17:57:07 INFO - "slavebuilddir": "test" 17:57:07 INFO - } 17:57:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 17:57:07 INFO - Running post-action listener: _resource_record_post_action 17:57:07 INFO - ##### 17:57:07 INFO - ##### Running download-and-extract step. 17:57:07 INFO - ##### 17:57:07 INFO - Running pre-action listener: _resource_record_pre_action 17:57:07 INFO - Running main action method: download_and_extract 17:57:07 INFO - mkdir: /builds/slave/test/build/tests 17:57:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:07 INFO - https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:57:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 17:57:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 17:57:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 17:57:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 17:57:29 INFO - Downloaded 1165 bytes. 17:57:29 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 17:57:29 INFO - Using the following test package requirements: 17:57:29 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 17:57:29 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:57:29 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 17:57:29 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:57:29 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 17:57:29 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:57:29 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:57:29 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 17:57:29 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 17:57:29 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:57:29 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 17:57:29 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:57:29 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 17:57:29 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:57:29 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 17:57:29 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 17:57:29 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 17:57:29 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 17:57:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:29 INFO - https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:57:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 17:57:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 17:57:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 17:57:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 17:57:33 INFO - Downloaded 18351511 bytes. 17:57:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:57:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:57:36 INFO - Return code: 0 17:57:36 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 17:57: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:36 INFO - https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:57:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 17:57:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 17:57:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 17:57:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 17:57:41 INFO - Downloaded 18351511 bytes. 17:57:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:57:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:57:45 INFO - Return code: 0 17:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:45 INFO - https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:57:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 17:57:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 17:57:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 17:57:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 17:57:46 INFO - Downloaded 10840145 bytes. 17:57:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:57:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:57:46 INFO - Return code: 0 17:57:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:46 INFO - https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 17:57:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 17:57:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 17:57:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 17:57:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 17:57:54 INFO - Downloaded 86186621 bytes. 17:57:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 17:57:54 INFO - mkdir: /builds/slave/test/properties 17:57:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:57:54 INFO - Writing to file /builds/slave/test/properties/build_url 17:57:54 INFO - Contents: 17:57:54 INFO - build_url:https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 17:57:55 INFO - Running post-action listener: _resource_record_post_action 17:57:55 INFO - Running post-action listener: set_extra_try_arguments 17:57:55 INFO - ##### 17:57:55 INFO - ##### Running populate-webroot step. 17:57:55 INFO - ##### 17:57:55 INFO - Running pre-action listener: _resource_record_pre_action 17:57:55 INFO - Running main action method: populate_webroot 17:57:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:57:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:57:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:57:55 INFO - '--tppagecycles', 17:57:55 INFO - '7'], 17:57:55 INFO - 'tests': ['tsvgm']}}, 17:57:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:57:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:57:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:57:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:57:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:55 INFO - 'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:57:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:57:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:55 INFO - 'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['damp', 'tps']}, 17:57:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:55 INFO - 'tests': ['damp', 'tps']}, 17:57:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['dromaeo_dom']}, 17:57:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:57:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['a11yr', 17:57:55 INFO - 'ts_paint', 17:57:55 INFO - 'tpaint', 17:57:55 INFO - 'sessionrestore', 17:57:55 INFO - 'sessionrestore_no_auto_restore', 17:57:55 INFO - 'tabpaint']}, 17:57:55 INFO - 'other-e10s': {'tests': ['a11yr', 17:57:55 INFO - 'ts_paint', 17:57:55 INFO - 'tpaint', 17:57:55 INFO - 'sessionrestore', 17:57:55 INFO - 'sessionrestore_no_auto_restore', 17:57:55 INFO - 'tabpaint']}, 17:57:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:57:55 INFO - 'ts_paint', 17:57:55 INFO - 'tpaint', 17:57:55 INFO - 'sessionrestore', 17:57:55 INFO - 'sessionrestore_no_auto_restore', 17:57:55 INFO - 'tabpaint']}, 17:57:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:57:55 INFO - 'ts_paint', 17:57:55 INFO - 'tpaint', 17:57:55 INFO - 'sessionrestore', 17:57:55 INFO - 'sessionrestore_no_auto_restore', 17:57:55 INFO - 'tabpaint']}, 17:57:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['a11yr', 17:57:55 INFO - 'ts_paint', 17:57:55 INFO - 'tpaint', 17:57:55 INFO - 'sessionrestore', 17:57:55 INFO - 'sessionrestore_no_auto_restore', 17:57:55 INFO - 'tabpaint']}, 17:57:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['a11yr', 17:57:55 INFO - 'ts_paint', 17:57:55 INFO - 'tpaint', 17:57:55 INFO - 'sessionrestore', 17:57:55 INFO - 'sessionrestore_no_auto_restore', 17:57:55 INFO - 'tabpaint']}, 17:57:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['tsvgx', 17:57:55 INFO - 'tsvgr_opacity', 17:57:55 INFO - 'tart', 17:57:55 INFO - 'tscrollx', 17:57:55 INFO - 'cart']}, 17:57:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:57:55 INFO - 'tsvgr_opacity', 17:57:55 INFO - 'tart', 17:57:55 INFO - 'tscrollx', 17:57:55 INFO - 'cart']}, 17:57:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:55 INFO - 'talos_options': ['--disable-e10s'], 17:57:55 INFO - 'tests': ['tp5o']}, 17:57:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:55 INFO - 'tests': ['tp5o']}, 17:57:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:55 INFO - 'talos_options': ['--disable-e10s', 17:57:55 INFO - '--xperf_path', 17:57:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:57:55 INFO - 'tests': ['tp5n']}, 17:57:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:55 INFO - 'talos_options': ['--xperf_path', 17:57:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:57:55 INFO - 'tests': ['tp5n']}}, 17:57:55 INFO - 'talos.zip': {'path': '', 17:57:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:57:55 INFO - Running post-action listener: _resource_record_post_action 17:57:55 INFO - ##### 17:57:55 INFO - ##### Running create-virtualenv step. 17:57:55 INFO - ##### 17:57:55 INFO - Running pre-action listener: _resource_record_pre_action 17:57:55 INFO - Running main action method: create_virtualenv 17:57:55 INFO - Creating virtualenv /builds/slave/test/build/venv 17:57:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:57:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:57:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:57:55 INFO - Using real prefix '/tools/python27' 17:57:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:57:56 INFO - Installing distribute.............................................................................................................................................................................................done. 17:57:59 INFO - Installing pip.................done. 17:57:59 INFO - Return code: 0 17:57:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:57:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:59 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 0x10b41aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd948e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.kI9pCHTT93/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.i0unTEexnC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:59 INFO - 'HOME': '/Users/cltbld', 17:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:59 INFO - 'LOGNAME': 'cltbld', 17:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:59 INFO - 'MOZ_NO_REMOTE': '1', 17:57:59 INFO - 'NO_EM_RESTART': '1', 17:57:59 INFO - 'PAGER': '/bin/cat', 17:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:59 INFO - 'PWD': '/builds/slave/test', 17:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:59 INFO - 'SHELL': '/bin/bash', 17:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:57:59 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:59 INFO - 'USER': 'cltbld', 17:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:59 INFO - 'XPC_FLAGS': '0x0', 17:57:59 INFO - 'XPC_SERVICE_NAME': '0', 17:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:57:59 INFO - Cleaning up... 17:57:59 INFO - Return code: 0 17:57:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:57:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:59 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:59 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b41aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd948e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.kI9pCHTT93/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.i0unTEexnC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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:57:59 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>=0.7.1'] in /builds/slave/test/build 17:57:59 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>=0.7.1 17:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:59 INFO - 'HOME': '/Users/cltbld', 17:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:59 INFO - 'LOGNAME': 'cltbld', 17:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:59 INFO - 'MOZ_NO_REMOTE': '1', 17:57:59 INFO - 'NO_EM_RESTART': '1', 17:57:59 INFO - 'PAGER': '/bin/cat', 17:57:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:59 INFO - 'PWD': '/builds/slave/test', 17:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:59 INFO - 'SHELL': '/bin/bash', 17:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:57:59 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:59 INFO - 'USER': 'cltbld', 17:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:59 INFO - 'XPC_FLAGS': '0x0', 17:57:59 INFO - 'XPC_SERVICE_NAME': '0', 17:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:00 INFO - Downloading/unpacking psutil>=0.7.1 17:58: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:58: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:58: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:58: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:58: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:58: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:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:58:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:58:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:58:02 INFO - Installing collected packages: psutil 17:58:02 INFO - Running setup.py install for psutil 17:58:03 INFO - building 'psutil._psutil_osx' extension 17:58:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:58:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:58:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:58:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:58:03 INFO - building 'psutil._psutil_posix' extension 17:58:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:58:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:58:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:58:03 INFO - ^ 17:58:04 INFO - 1 warning generated. 17:58:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:58:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:58:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:58:04 INFO - Successfully installed psutil 17:58:04 INFO - Cleaning up... 17:58:04 INFO - Return code: 0 17:58:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b41aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd948e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.kI9pCHTT93/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.i0unTEexnC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:58: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 17:58: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 mozsystemmonitor==0.0.0 17:58:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:58:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:04 INFO - 'HOME': '/Users/cltbld', 17:58:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:04 INFO - 'LOGNAME': 'cltbld', 17:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:04 INFO - 'MOZ_NO_REMOTE': '1', 17:58:04 INFO - 'NO_EM_RESTART': '1', 17:58:04 INFO - 'PAGER': '/bin/cat', 17:58:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:04 INFO - 'PWD': '/builds/slave/test', 17:58:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:04 INFO - 'SHELL': '/bin/bash', 17:58:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:58:04 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:58:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:04 INFO - 'USER': 'cltbld', 17:58:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:04 INFO - 'XPC_FLAGS': '0x0', 17:58:04 INFO - 'XPC_SERVICE_NAME': '0', 17:58:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:58:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:58:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:58:06 INFO - Installing collected packages: mozsystemmonitor 17:58:06 INFO - Running setup.py install for mozsystemmonitor 17:58:07 INFO - Successfully installed mozsystemmonitor 17:58:07 INFO - Cleaning up... 17:58:07 INFO - Return code: 0 17:58:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:58:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:07 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 0x10b41aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd948e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.kI9pCHTT93/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.i0unTEexnC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:58:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:58:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:58:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:58:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:07 INFO - 'HOME': '/Users/cltbld', 17:58:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:07 INFO - 'LOGNAME': 'cltbld', 17:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:07 INFO - 'MOZ_NO_REMOTE': '1', 17:58:07 INFO - 'NO_EM_RESTART': '1', 17:58:07 INFO - 'PAGER': '/bin/cat', 17:58:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:07 INFO - 'PWD': '/builds/slave/test', 17:58:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:07 INFO - 'SHELL': '/bin/bash', 17:58:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:58:07 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:58:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:07 INFO - 'USER': 'cltbld', 17:58:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:07 INFO - 'XPC_FLAGS': '0x0', 17:58:07 INFO - 'XPC_SERVICE_NAME': '0', 17:58:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:07 INFO - Downloading/unpacking blobuploader==1.2.4 17:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:10 INFO - Downloading blobuploader-1.2.4.tar.gz 17:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:58:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:58:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:11 INFO - Downloading docopt-0.6.1.tar.gz 17:58:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:58:11 INFO - Installing collected packages: blobuploader, requests, docopt 17:58:11 INFO - Running setup.py install for blobuploader 17:58:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:58:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:58:11 INFO - Running setup.py install for requests 17:58:12 INFO - Running setup.py install for docopt 17:58:12 INFO - Successfully installed blobuploader requests docopt 17:58:12 INFO - Cleaning up... 17:58:12 INFO - Return code: 0 17:58:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:12 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 0x10b41aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd948e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.kI9pCHTT93/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.i0unTEexnC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:58:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:58:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:58:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:12 INFO - 'HOME': '/Users/cltbld', 17:58:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:12 INFO - 'LOGNAME': 'cltbld', 17:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:12 INFO - 'MOZ_NO_REMOTE': '1', 17:58:12 INFO - 'NO_EM_RESTART': '1', 17:58:12 INFO - 'PAGER': '/bin/cat', 17:58:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:12 INFO - 'PWD': '/builds/slave/test', 17:58:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:12 INFO - 'SHELL': '/bin/bash', 17:58:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:58:12 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:58:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:12 INFO - 'USER': 'cltbld', 17:58:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:12 INFO - 'XPC_FLAGS': '0x0', 17:58:12 INFO - 'XPC_SERVICE_NAME': '0', 17:58:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:58:12 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-59kfkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:58:12 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-DpuT9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:58:12 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-_dq3l5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:58:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:58:12 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-N_MjkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-UezBU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-60oKqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-DaPLq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-HuGf91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-H6obGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Ck2J0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-cio5ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-D4ODQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-OdCmXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:58:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:58:13 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-ghm2JW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:58:14 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Kd0w3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:58:14 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-EYls9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:58:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:58:14 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-JRYp1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:58:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:58:14 INFO - Running setup.py install for manifestparser 17:58:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:58:14 INFO - Running setup.py install for mozcrash 17:58:14 INFO - Running setup.py install for mozdebug 17:58:14 INFO - Running setup.py install for mozdevice 17:58:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:58:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:58:14 INFO - Running setup.py install for mozfile 17:58:15 INFO - Running setup.py install for mozhttpd 17:58:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:58:15 INFO - Running setup.py install for mozinfo 17:58:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:58:15 INFO - Running setup.py install for mozInstall 17:58:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:58:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:58:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:58:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:58:15 INFO - Running setup.py install for mozleak 17:58:15 INFO - Running setup.py install for mozlog 17:58:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:58:15 INFO - Running setup.py install for moznetwork 17:58:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:58:15 INFO - Running setup.py install for mozprocess 17:58:16 INFO - Running setup.py install for mozprofile 17:58:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:58:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:58:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:58:16 INFO - Running setup.py install for mozrunner 17:58:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:58:16 INFO - Running setup.py install for mozscreenshot 17:58:16 INFO - Running setup.py install for moztest 17:58:16 INFO - Running setup.py install for mozversion 17:58:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:58:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:58:16 INFO - Cleaning up... 17:58:16 INFO - Return code: 0 17:58:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:58:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:16 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 0x10b41aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd948e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.kI9pCHTT93/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.i0unTEexnC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:58:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:58:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:58:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:58:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:16 INFO - 'HOME': '/Users/cltbld', 17:58:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:16 INFO - 'LOGNAME': 'cltbld', 17:58:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:16 INFO - 'MOZ_NO_REMOTE': '1', 17:58:16 INFO - 'NO_EM_RESTART': '1', 17:58:16 INFO - 'PAGER': '/bin/cat', 17:58:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:16 INFO - 'PWD': '/builds/slave/test', 17:58:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:16 INFO - 'SHELL': '/bin/bash', 17:58:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:58:16 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:58:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:16 INFO - 'USER': 'cltbld', 17:58:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:16 INFO - 'XPC_FLAGS': '0x0', 17:58:16 INFO - 'XPC_SERVICE_NAME': '0', 17:58:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:58:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-d_z30v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:58:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-ZN3jNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:58:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-HG03y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:58:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-LEqszw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:58:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-DhSqua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:58:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-R4hshO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:58:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-vUTVfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:58:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:58:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:58:17 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-AEYZTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:58:18 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Ih8qZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:58:18 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-Pr_F1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:58:18 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-TdqYqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:58:18 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-t3gXjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:58:18 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-pkCj68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:58:18 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:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:58:18 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-22RKn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:58:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:58:18 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-1Xngfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:58:18 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:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:58:18 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-NogU0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:58:18 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:58:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:58:18 INFO - Running setup.py (path:/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/pip-qfWYOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:58:18 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:58:18 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:58:18 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:58:18 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:58:18 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:58:18 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:58:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:21 INFO - Downloading blessings-1.6.tar.gz 17:58:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:58:21 INFO - Installing collected packages: blessings 17:58:21 INFO - Running setup.py install for blessings 17:58:21 INFO - Successfully installed blessings 17:58:21 INFO - Cleaning up... 17:58:21 INFO - Return code: 0 17:58:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:58:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:58:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:58:22 INFO - Reading from file tmpfile_stdout 17:58:22 INFO - Current package versions: 17:58:22 INFO - blessings == 1.6 17:58:22 INFO - blobuploader == 1.2.4 17:58:22 INFO - docopt == 0.6.1 17:58:22 INFO - manifestparser == 1.1 17:58:22 INFO - mozInstall == 1.12 17:58:22 INFO - mozcrash == 0.17 17:58:22 INFO - mozdebug == 0.1 17:58:22 INFO - mozdevice == 0.48 17:58:22 INFO - mozfile == 1.2 17:58:22 INFO - mozhttpd == 0.7 17:58:22 INFO - mozinfo == 0.9 17:58:22 INFO - mozleak == 0.1 17:58:22 INFO - mozlog == 3.1 17:58:22 INFO - moznetwork == 0.27 17:58:22 INFO - mozprocess == 0.22 17:58:22 INFO - mozprofile == 0.28 17:58:22 INFO - mozrunner == 6.11 17:58:22 INFO - mozscreenshot == 0.1 17:58:22 INFO - mozsystemmonitor == 0.0 17:58:22 INFO - moztest == 0.7 17:58:22 INFO - mozversion == 1.4 17:58:22 INFO - psutil == 3.1.1 17:58:22 INFO - requests == 1.2.3 17:58:22 INFO - wsgiref == 0.1.2 17:58:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:58:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:22 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 0x10b41aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd948e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.kI9pCHTT93/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.i0unTEexnC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:58:22 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:58:22 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:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:22 INFO - 'HOME': '/Users/cltbld', 17:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:22 INFO - 'LOGNAME': 'cltbld', 17:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:22 INFO - 'MOZ_NO_REMOTE': '1', 17:58:22 INFO - 'NO_EM_RESTART': '1', 17:58:22 INFO - 'PAGER': '/bin/cat', 17:58:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:22 INFO - 'PWD': '/builds/slave/test', 17:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:22 INFO - 'SHELL': '/bin/bash', 17:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:58:22 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:22 INFO - 'USER': 'cltbld', 17:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:22 INFO - 'XPC_FLAGS': '0x0', 17:58:22 INFO - 'XPC_SERVICE_NAME': '0', 17:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:22 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:58:22 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:58:22 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:58:22 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:58:22 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:58:22 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:58:22 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:58:22 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:58:22 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:58:22 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:58:22 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:58:22 INFO - Cleaning up... 17:58:22 INFO - Return code: 0 17:58:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:58:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:22 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 0x10b41aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd948e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.kI9pCHTT93/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.i0unTEexnC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:58:22 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:58:22 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:58:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:58:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:22 INFO - 'HOME': '/Users/cltbld', 17:58:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:22 INFO - 'LOGNAME': 'cltbld', 17:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:22 INFO - 'MOZ_NO_REMOTE': '1', 17:58:22 INFO - 'NO_EM_RESTART': '1', 17:58:22 INFO - 'PAGER': '/bin/cat', 17:58:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:22 INFO - 'PWD': '/builds/slave/test', 17:58:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:22 INFO - 'SHELL': '/bin/bash', 17:58:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:58:22 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:58:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:22 INFO - 'USER': 'cltbld', 17:58:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:22 INFO - 'XPC_FLAGS': '0x0', 17:58:22 INFO - 'XPC_SERVICE_NAME': '0', 17:58:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:22 INFO - Downloading/unpacking jsonschema 17:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:58:24 INFO - Downloading/unpacking functools32 (from jsonschema) 17:58:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:25 INFO - Downloading functools32-3.2.3-2.tar.gz 17:58:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:58:25 INFO - warning: no files found matching '*.txt' 17:58:25 INFO - no previously-included directories found matching 'build' 17:58:25 INFO - no previously-included directories found matching 'dist' 17:58:25 INFO - no previously-included directories found matching '.git*' 17:58:25 INFO - Installing collected packages: jsonschema, functools32 17:58:25 INFO - Running setup.py install for functools32 17:58:25 INFO - warning: no files found matching '*.txt' 17:58:25 INFO - no previously-included directories found matching 'build' 17:58:25 INFO - no previously-included directories found matching 'dist' 17:58:25 INFO - no previously-included directories found matching '.git*' 17:58:25 INFO - Successfully installed jsonschema functools32 17:58:25 INFO - Cleaning up... 17:58:25 INFO - Return code: 0 17:58:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:58:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:58:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:58:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:58:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b41aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9bd948e5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, '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.kI9pCHTT93/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.i0unTEexnC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:58:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:58:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:58:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:58:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:25 INFO - 'HOME': '/Users/cltbld', 17:58:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:25 INFO - 'LOGNAME': 'cltbld', 17:58:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:25 INFO - 'MOZ_NO_REMOTE': '1', 17:58:25 INFO - 'NO_EM_RESTART': '1', 17:58:25 INFO - 'PAGER': '/bin/cat', 17:58:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:25 INFO - 'PWD': '/builds/slave/test', 17:58:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:25 INFO - 'SHELL': '/bin/bash', 17:58:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:58:25 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:58:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:25 INFO - 'USER': 'cltbld', 17:58:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:25 INFO - 'XPC_FLAGS': '0x0', 17:58:25 INFO - 'XPC_SERVICE_NAME': '0', 17:58:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:58:25 INFO - Downloading/unpacking flake8 17:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:58:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:58:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:58:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:58:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:58:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:58:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:58:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:58:29 INFO - no previously-included directories found matching 'docs/_build' 17:58:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:58:29 INFO - Running setup.py install for pep8 17:58:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:58:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:58:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:58:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:58:29 INFO - no previously-included directories found matching 'docs/_build' 17:58:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:58:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:58:29 INFO - Cleaning up... 17:58:29 INFO - Return code: 0 17:58:29 INFO - Running post-action listener: _resource_record_post_action 17:58:29 INFO - Running post-action listener: _start_resource_monitoring 17:58:30 INFO - Starting resource monitoring. 17:58:30 INFO - ##### 17:58:30 INFO - ##### Running install step. 17:58:30 INFO - ##### 17:58:30 INFO - Running pre-action listener: _resource_record_pre_action 17:58:30 INFO - Running main action method: install 17:58:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:58:30 INFO - Reading from file tmpfile_stdout 17:58:30 INFO - Detecting whether we're running mozinstall >=1.0... 17:58:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:58:30 INFO - Reading from file tmpfile_stdout 17:58:30 INFO - Output received: 17:58:30 INFO - Usage: mozinstall [options] installer 17:58:30 INFO - Options: 17:58:30 INFO - -h, --help show this help message and exit 17:58:30 INFO - -d DEST, --destination=DEST 17:58:30 INFO - Directory to install application into. [default: 17:58:30 INFO - "/builds/slave/test"] 17:58:30 INFO - --app=APP Application being installed. [default: firefox] 17:58:30 INFO - mkdir: /builds/slave/test/build/application 17:58:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 17:58:52 INFO - Reading from file tmpfile_stdout 17:58:52 INFO - Output received: 17:58:52 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 17:58:52 INFO - Running post-action listener: _resource_record_post_action 17:58:52 INFO - ##### 17:58:52 INFO - ##### Running run-tests step. 17:58:52 INFO - ##### 17:58:52 INFO - Running pre-action listener: _resource_record_pre_action 17:58:52 INFO - Running main action method: run_tests 17:58:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:58:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:58:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:58:52 INFO - 2016-07-10 17:58:52.279 screenresolution[1640:7919] starting screenresolution argv=screenresolution get 17:58:52 INFO - 2016-07-10 17:58:52.282 screenresolution[1640:7919] Display 0: 1600x1200x32@60 17:58:52 INFO - 2016-07-10 17:58:52.287 screenresolution[1641:7921] starting screenresolution argv=screenresolution list 17:58:52 INFO - Available Modes on Display 0 17:58:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:58:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:58:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:58:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:58:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:58:52 INFO - Intel Iris: 17:58:52 INFO - Chipset Model: Intel Iris 17:58:52 INFO - Type: GPU 17:58:52 INFO - Bus: Built-In 17:58:52 INFO - VRAM (Dynamic, Max): 1536 MB 17:58:52 INFO - Vendor: Intel (0x8086) 17:58:52 INFO - Device ID: 0x0a2e 17:58:52 INFO - Revision ID: 0x0009 17:58:52 INFO - Displays: 17:58:52 INFO - Display: 17:58:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:58:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:58:52 INFO - Display Serial Number: 1600x1200 60 17:58:52 INFO - Main Display: Yes 17:58:52 INFO - Mirror: Off 17:58:52 INFO - Online: Yes 17:58:52 INFO - Rotation: Supported 17:58:52 INFO - Return code: 0 17:58:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:58:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:58:52 INFO - Python 2.7.3 17:58:52 INFO - Return code: 0 17:58:52 INFO - grabbing minidump binary from tooltool 17:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:58:52 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b9e00>, '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 0x10b122db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2b4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:58:52 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:58:52 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:58:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:58:52 INFO - Return code: 0 17:58:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:58:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:58:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:58:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:58:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:58:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:58:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:58:53 INFO - Return code: 0 17:58:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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:58:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0160 --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:58:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI9pCHTT93/Render', 17:58:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:58:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:58:53 INFO - 'HOME': '/Users/cltbld', 17:58:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:58:53 INFO - 'LOGNAME': 'cltbld', 17:58:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:58:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:58:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:58:53 INFO - 'MOZ_NO_REMOTE': '1', 17:58:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:58:53 INFO - 'NO_EM_RESTART': '1', 17:58:53 INFO - 'PAGER': '/bin/cat', 17:58:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:58:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:58:53 INFO - 'PWD': '/builds/slave/test', 17:58:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:58:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:58:53 INFO - 'SHELL': '/bin/bash', 17:58:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i0unTEexnC/Listeners', 17:58:53 INFO - 'TMPDIR': '/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/', 17:58:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:58:53 INFO - 'USER': 'cltbld', 17:58:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:58:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:58:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:58:53 INFO - 'XPC_FLAGS': '0x0', 17:58:53 INFO - 'XPC_SERVICE_NAME': '0', 17:58:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:58:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0160', '--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:58:53 INFO - mozversion application_buildid: 20160710161526 17:58:53 INFO - mozversion application_changeset: fa7a42657c48de78ba1a0fefd43d1c6ed233e97a 17:58:53 INFO - mozversion application_display_name: Firefox 17:58:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:58:53 INFO - mozversion application_name: Firefox 17:58:53 INFO - mozversion application_remotingname: firefox 17:58:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:58:53 INFO - mozversion application_vendor: Mozilla 17:58:53 INFO - mozversion application_version: 48.0 17:58:53 INFO - mozversion platform_buildid: 20160710161526 17:58:53 INFO - mozversion platform_changeset: fa7a42657c48de78ba1a0fefd43d1c6ed233e97a 17:58:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:58:53 INFO - mozversion platform_version: 48.0 17:58:53 INFO - using testdate: 1468198733 17:58:53 INFO - actual date: 1468198733 17:58:53 INFO - starting webserver on 'localhost:49275' 17:58:53 INFO - SUITE-START | Running 2 tests 17:58:53 INFO - TEST-START | dromaeo_css 17:58:53 INFO - operating with platform_type : mac_ 17:58:53 INFO - Initialising browser for dromaeo_css test... 17:58:53 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpcQbOYY/profile http://localhost:49275/getInfo.html) 17:58:56 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 17:58:56 INFO - PROCESS | 1659 | colorDepth:24 17:58:56 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 17:58:56 INFO - PROCESS | 1659 | __metrics 17:58:56 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:58:56 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:58:57 INFO - TEST-INFO | 1659: exit 0 17:58:57 INFO - Browser initialized. 17:58:57 INFO - Running cycle 1/1 for dromaeo_css test... 17:58:57 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpcQbOYY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:59:08 INFO - PROCESS | 1662 | RSS: Main: 161689600 17:59:08 INFO - PROCESS | 1662 | 18:00:00 INFO - PROCESS | 1662 | 1468198800290 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:02:10 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 18:02:11 INFO - PROCESS | 1662 | RSS: Main: 255422464 18:02:11 INFO - PROCESS | 1662 | 18:05:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 18:05:14 INFO - PROCESS | 1662 | RSS: Main: 298676224 18:05:14 INFO - PROCESS | 1662 | 18:08:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 18:08:17 INFO - PROCESS | 1662 | RSS: Main: 312356864 18:08:17 INFO - PROCESS | 1662 | 18:11:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 18:11:19 INFO - PROCESS | 1662 | RSS: Main: 305160192 18:11:19 INFO - PROCESS | 1662 | 18:14:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 18:14:23 INFO - PROCESS | 1662 | RSS: Main: 271392768 18:14:23 INFO - PROCESS | 1662 | 18:17:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 18:17:26 INFO - PROCESS | 1662 | RSS: Main: 316596224 18:17:26 INFO - PROCESS | 1662 | 18:17:26 INFO - PROCESS | 1662 | __start_tp_report 18:17:26 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 18:17:26 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 18:17:26 INFO - PROCESS | 1662 | |i|pagename|runs| 18:17:26 INFO - PROCESS | 1662 | |0;dojo.html;3291.2759192977546;3636.2727454509104;3639.3813051781194;3640.908977275568;3735.533058367704;5985.70071496425;6012.3085845127825;6017.578769486137;6020.879821485495;6069.726862291196;6024.69876506174;6043.486303664183;6059.545534084939;6064.090386442036;6080.665563394023;5270.578353731692;5341.519263266296;5404.4595540446035;5436.83689489314;5481.150421684645;5402;5452.2094296327205;5454.236406903036;5465.535429488498;5467.753951072192;4528.366028755977;5174.611904107188;5367.785288588452;5399.271098401705;5461.1535212042;5433.1850222466355;5480.534154596824;5482.068048431776;5517.724113794295;5521.641093328961;5285.524302812727;5308.177232528926;5336.092864213093;5368.8389348319615;5397.109476936301;5862.12068189775;5894.970525147347;5920.703964801743;5963.7912673056235;6048.183495228176;6270.592411493284;6292.968535157295;6310.684465776692;6314.93685063148;6321.683915804238;6200.627962322275;6319.5576309658345;6386.2975072741965;6401.839954001187;6408.263049749292;6075.634845698943;6077.908831367489;6121.265448146251;6123.510119190454;6129.233845769279;6481.967590162019;6495.1556297681;6496.025596160519;6513.674316284167;6529.2817790043055;6221.408966148299;6231.003039513746;6321.273053598895;6343.27052388972;6344.206974128234;6318.115463835067;6333.303336632966;6339.556231063873;6340.619562826245;6347.492200623939;6249.750009999549;6303;6306.990881458944;6322.683865806691;6367.235931688227;5097.46476619957;6236.968815155895;6258.906116408258;6302.432781049636;6338.778142765073;5052.823151189691;5081.390233171969;5083.87290317745;5085.720285384339;5096.337476128079;4591.609713174417;4599.770011499479;4602.7468489233415;4606.539346065434;4665.626749860003;5968.253968253968;6031.366706495843;6078.969605151946;6112.229929638693;6130.367562715059;6329.683515824282;6338.904916426257;6347.619142851444;6348.079528468346;6359.077933699587;6038.939610603926;6042.516598672096;6046.304674962347;6051.394860513913;6068.362821903725;4721.669483136147;5475.753591088331;5525.502704756591;5560.944390556043;5643.351014633287;6576.736930522821;6645.867082658417;6669.599824010575;6674.098996635392;6681.432078273401;6262.091996660368;6408.327125651926;6413.230412350548;6420.518461115528;6428.55000149985;6543.247526534509;6594.208694956636;6627.403533681992;6641.203055633355;6649.301823308677;6286.622802631857;6287.339829317856;6311.779087731908;6324.367563243639;6325.177726895474;6108.645631960618;6313.40022697843;6316.652584107918;6317.273513546001;6403;6249.0626406039455;6283.748650053946;6297.52768542352;6311.652859092794;6320.1151838743535;6283.937160628335;6293.622382657055;6296.874062518632;6297.370262973666;6303.306636270098;6105.084237364431;6184.907226391518;6239.344868788893;6250.249970003447;6284.937150628435;5167.199084141964;6215.9378406215355;6282.811515654537;6291.213598300213;6298.401651843067;6166.81499555014;6251.46862516673;6253.593516421418;6254.843628909313;6261.96869015652 18:17:26 INFO - PROCESS | 1662 | |1;ext.html;521.1401188039735;531.8218396837062;580.625496554722;606.1634943777586;610.2280615021995;26089;33652.99999999997;33714.66285337152;33721.66278337216;33835.66164338359;26391;39215.41176882349;39515.0121246969;39542.60457395425;39585.20829583407;9834.852477212848;10063.64777232797;10065.899341006607;10081.596736130547;10083.243756718239;9276.397034192756;9901.20790336775;10023.799524009515;10035;10038.799224015514;11996.160268781188;12241.693957651085;12252.203606765575;12256;12282.877171228263;11895.464704088294;12051.93974030133;12063.758724825542;12064.095192860483;12101.39493025354;11780.998615117725;11905.880941190564;11916.165868389216;11917.94041029798;11918.582849600223;41154.79422602868;41316.793416033026;41637.750867474024;43567.782161088995;43628.781856090514;3289.407906576815;3378.763486555942;3420.1278673937986;3421.230223199785;3437.123533498942;27744.8902043918;28020.43959120806;29119;29872.850635746683;30164.095077147722;2783.9304017399522;2807.2420446479337;2815.9155225343275;2846.8576571171357;2904.07069737684;342760;348522.99999999744;349929.25035374914;350252;351413;37883.43174852379;38802;63787.36212637907;64280.35719642837;68312;37602.811985939894;37704.811475942995;37896.81051594725;37956.810215948746;37990.43014354787;16423.425180118877;16607.41874034403;16616.83383166153;16617.08606026656;16660.083695396628;13938.024338296222;14006.369713362923;14009.229492377814;14025.438982440792;14041.85958140426;8181.590920453954;8314.334853211729;8553.572321383906;8617.267532259702;8751;3093.6442309134286;3513.683768460851;3565.358235517632;3568.3576956148145;3575;756.5385115079797;786.7364432914923;1037.9481025948671;1090.3675867996542;1130.2032067392634;28645.427091458056;29887.850560747058;29952.700472995428;30328.54507182394;30983.69016309853;965.82615129276;1161.6050542815483;1170.0814860334597;1175.8177482490228;1183.0180949811636;15352.769708454382;15729.764053539206;15845;15872.477700051237;15990.76013859793;623275;631499;632335;632748;633133;66961;67175.66412167907;67449.66275168593;67451.98822017673;67488;1236.3879879459726;1435.5047508609514;1536.5006372928842;1549.3725041358268;1607.284758282553;26025;29889.850550747105;31177.8441107793;32051.83974080208;32565;56909;57390;57838.84322313446;58141.41858581529;58176.127358088816;521529;525870;526317;526688;528600;25435.61846572266;29100.12699619015;29421.705782942754;29546.852265738533;29815.80736770505;45023.774881125384;45590.544094559955;46644.53355466402;46913.296300556205;47434.52565474439;37019.25961480809;37105.81447092747;39569.80215098906;39757.2048559033;40387.596124039555;34350.82824585861;37386.69146579857;38313.808430957666;39417.802910985265;39988;38992.80503597464;39342.2131557373;40596.79701601473;40926.38610420906;41852.3722144162;32444.026679199662;34237.657623423445;34796.65203348036;34828.82585587056;34948.82525587457;19996.800031999494;26133;26141.346466338495;26405.075822346895;26668.466630667666 18:17:26 INFO - PROCESS | 1662 | |2;jquery.html;416.94788151481066;418.4580967646897;418.78851180154015;419.9265128602494;432.1551367077364;20794.688079678817;21326.573468530638;21537.24619638313;22665.54668906625;23355.76644233557;20077.498062548442;21784.673229901564;21900.23349182779;22631.207907723234;22708.432289192777;6083.148359229712;6236.812895613116;6279.340669229734;6279.591826531284;6292.119103325538;4555.681102322822;4781.6413768967295;4827.517248275162;6234.625922444668;6310.842228944291;6901.482388820833;6904.516683832134;6908.896366554506;6911.758088466905;6922.307769223063;6659.334066593302;6756.8310792230095;6826.112605361272;6861.725530978755;6876.518643694943;4083.530394004698;5714.1428785682065;6027.728752206117;6036.486898613623;6044.576879618428;26880.86559567209;27566.0351887685;28074.438511229866;28083.0170944016;28203.57694634582;2234.653628687556;2274.0108052996834;2362.7519110493495;2431.7811396974357;2474.91337803176;15535.990160639521;15797;15853.048817071012;16023.958442701187;16198;1776.4493007167819;1782.777152855893;1791.9193636286398;1818.8363047325672;1862.2923289150037;74131.62934185349;89405.65891511568;92084;92937;93243.06756932412;10867.619633312954;10997.175211859143;11577.189596728316;11905.880941190651;12001.339926303963;15630.687386252439;15658.295376707847;15698.450554230547;15722.371105155666;15774.290156942965;7867.331276841533;9097.863532047024;9101.77245568866;9260.675876344296;9343.159115679622;7556.697732090654;9245.029271926485;9253;9269.397489163182;9379.202767764817;4404.942813724683;4569.063342014879;4729;4786.569208771228;4789.760511974457;1995.1121750821035;2034.4334862156056;2040.2247146084394;2053.5276886316233;2069.3584988653565;900.7297810656777;984.2372161574693;999.3953658036928;1012.1447376966452;1054.1566746602687;19971;20943.37169884906;20985.79014209869;21025.26411575618;21169;840.1304649687544;965.4062751407834;1006.5118417567473;1031.896810318962;1060.0724366179593;8762.342824288076;8838.690645827493;10994.670159895217;11159.051480624237;11189.608363707232;634328;637591;645344;660343;663206;116739.41630291964;120076;120570;121397;123659.38170309091;1045.602670985036;1060;1060.390275591538;1075.7310672331917;1077.0145317034983;18191.908485490912;18443.907780461013;18446.81553184451;19799.01004949718;19854.305099321453;49076.75461622669;49862;50702.49297506978;50841;52041.35710500229;71643.13427463052;72533.63733181512;74126.25873741193;75000.62499687467;75285;8064.15326390744;8253.298469630148;8383.916160838313;8396.580170991549;8462.153784621489;9173.678921237724;11730.824037639271;12382.938085309515;12906.806397904227;13120.60638180856;10840.349579025284;11043.503042362952;11224.15818813576;11320.26418282809;11434.199606027498;10925;10990.670279891616;11070.667879963614;11363.92042755937;11581.768364632491;6065.211522502046;7142.464425129212;10519.21105917044;11488.885111148782;11592.608886933622;8238.958805205935;8329.333653307842;8339.83320333602;8493.150684931705;8510.063892971892;9765.658201962897;11414.657560273205;11418.828717569077;11740.647780566596;11751.823722644338 18:17:26 INFO - PROCESS | 1662 | |3;mootools.html;1497.5282785922427;1628.2998310726387;1658.842409971053;1773.9822601773988;1954.7458830352061;8370.497770133796;8836.602352894119;9030.72907812765;9034.59344329505;9046.728598142057;8881.333899957497;9151.359404841663;9152.771180720485;9162.496062716547;9173.77065573361;2537.9873100634472;2577.0078519769836;2577.8066645001604;2578.1105518596055;2579.85810780407;2425.126954296452;2425.405775584984;2434.549608322457;2439.9024039038504;2466.025919761693;2533.8353007054484;2557;2574.5237131130707;2576.227131860444;2579.1102069785893;2508.8745562721974;2529.3170843872053;2529.8355606885493;2564.5768448206118;2574.060467929202;2102.400815767499;2557.9872100639377;2567.550678631251;2570.9485810283877;2582.5609646359976;8894.377393582452;9189.264858811279;9307.278153541549;9308.8603670945;9310.208632266265;5636.436356364372;5730.68481233532;5733.312002559678;5804.216430781875;5809.622374545684;3673.2286219893854;3721.9627803721887;3796.335641262768;3803.3724435468116;3819.9618003819883;1054.0882136951482;1074.570171931226;1076.5155679943978;1092.6940456672144;1118.546988469671;12289.815652765215;12418.75162496745;12517.937410312981;12580;12582.559610413591;4767.332573439721;4807.145352028328;4815.6388270879825;4866.391701037371;4884.072026314989;9724.319297649237;9742.415455072576;9754.414735115915;9758.219342452587;9787.706368808947;5588.497035266845;5590.748416321276;5591.552675786009;5601;5602.523785478198;4649.488556258809;4818.241341910248;5022.84931452057;5137.922931156036;5175.430702622709;4000.5599384067727;4005.379166229181;4083.9183216335505;4090.1819636072905;4137.172565486914;1270.6632742323293;1283.6983308922395;1286.3825363825417;1297.8896793772446;1299.4867027524076;962.6390103711109;987.6790043235977;998.4458625462938;1008.606643409071;1021.4484178543652;5890.263717035371;6073.392660733891;6098.261995331398;6165.537584681167;6446.29090800021;873.3231745397368;991.9553620087113;992.2062350119875;1028.711960651019;1052.9315594486334;3986.3223252047223;4067.3085575452246;4100.138970816102;4166.687498437629;4184.309588917856;10855.728606784893;11242.606508772154;11245.606403775992;11408.771824563462;11411.714707132222;11133.721656958642;11233.494492747846;11252.212345135762;11252.437378131224;11272.436378181059;8321.251087402163;8348.165183481724;8577.01364343096;8664.263537599249;8675.6529738811;5332.893342133213;5837.182794408871;5975.940240597713;6054.576179667381;6139.2325959255095;7939.6030198491;9399.671011514838;9675.612975480903;9829.950850245703;9846.655367062405;8300.750977470685;9646.517674116125;9864.21086313079;10018.799624007333;10187.43969081678;7117.82205444868;7281.308275713799;7782.88325675104;7991.2807847294025;8215.09633940278;8278.958605206935;8628.611712472828;8645.092265312303;8661.350398719995;8662.263707584925;7449.217832127549;7580;7632.1604623492485;7665.8466830661955;7716.5755883426955;7087.830507966128;7290.234525374972;7436.367908727602;7568.091828980558;7635.121960974559;6443.355664433519;7206.063211782434;7252.093488313961;7253.637318134178;7254.818629534304;5012;5033.672811267256;5134.998675258438;5201.427842937198;5234.555062819703;4747.430308363018;4957.776900039579;4964.304997300309;5165.741712914414;5218.217267409919 18:17:26 INFO - PROCESS | 1662 | |4;prototype.html;326.38965135197185;331.4547569248586;334.0213175396089;350.6563567703649;354.6524406082038;6796.694148763305;6835.111435513382;6851.588904665724;6860.005299231609;6869;6094.7810437912485;6732.697028633711;6756.864862702743;6777.2545020047755;6784.694688739006;4333.263345231318;4850.781714822824;4894.9965257122385;4937.7777999990085;4992.276119962603;4883.1698611236;4963.578095861856;4972.378452693413;4987.102321582114;4991.301217829507;4929.21132618781;4929.580985616227;4929.728864912428;4949.282354058677;4960.801567937297;4707.882302942419;4890.388701412324;4899.1426500362295;4899.681520701179;4909.116359055369;4177.4569305990335;4266.679999000087;4270.59429354214;4282.635975942048;4297.076128632328;6375.4262116409745;6404.039394090877;6466.967665161691;6471.255805582324;6500.577462464925;5938.376470470582;5941.524678025791;5954.46409823118;5968.134620480049;5973.551983601247;6949.131358580177;7238.384737297336;7249.093863267091;7257.6371181441245;7270.127584689871;4819.2530157825595;4833.492483289274;4853.4418541867435;4863.805447782084;4866.197077482211;7492.925070749277;7680.38556915451;7693.653785579606;7699.884501732478;7710.614469276514;4966.130927087817;4982.252662100642;4984.554479201063;4996.550310472002;4996.825111121093;5058.3929928408825;5063.417706963673;5069.264956581275;5070.087384270793;5074.543291103819;5745.482906538432;5958.165856780054;5959.672218027963;5965.224520812353;5974.103884417285;7250.274972502813;7297.963510182415;7314.45141614381;7328.303811137934;7329.633518324063;1722.0098443395098;1753.1234382808595;1754.929802807899;1757.1214392803597;1758.9560260993578;848.4951453884929;975.190591808797;1099.6646022962914;1109.755853712182;1141.5490881101919;6650.3682150195655;6805.183377994574;6807.149106361705;6815.625140617214;6886.8622627548175;7339.559626422431;7451;7453.888191677129;7632.847343053108;7638;6942.236354001056;6965.442764578822;6971.6862741176765;6976.546524475994;6980.371766541036;3944.9211015779524;4012.217617564547;4015.638592526687;4015.9799201003807;4019.055521952339;7245.347918687238;7673.731419400294;7958.2041795821115;7960.641771120314;7980.68077276914;7811;7856.960715196502;7865.724699635485;7871.960640196762;7884.989327721509;7967.402444816662;7974.202579742096;7980.760577182694;8007.439479236514;8010.679572817022;7325.706971721072;7599.240075992578;7600.543967361976;7608.733694320894;7616.8476630465975;7425.554466732014;7481.4014878808475;7482.663280152515;7489.70041198346;7503.062117235346;6405.327440618855;7914.24814642608;7966.083900351534;7969.840603187788;7977.521348719096;4971.428285747185;4978.327925730125;4982.900341993212;4984.526469985346;4997.475265097112;7231.5299505531975;7576.24237576238;7578.280063393969;7585.506942048749;7596.316331530189;6997.300269973166;7011.754588589579;7286.089238845145;7339.119305683352;7361.2638736125955;7308.488405811542;7312.780616581511;7328.105398367721;7330.120385553768;7342.596157211405;5798.391168927202;7185.964070179615;7195.46034047459;7199.1361036675935;7201.3518783309755;6169.598976066542;7045.859082818418;7284.745033923788;7309.451791115794;7314.268573142643;6820.658967051727;6961.686724097326;6996.425804194016;7162.283771622796;7186.245444228281 18:17:26 INFO - PROCESS | 1662 | |5;yui.html;609.8140067279479;686.2451303566078;693.3136195166783;770.1412924589085;773.5861314196906;15363.385464581403;15548.45580404686;15618.843811561881;15647.982881112734;15652.92173539132;14203.289835508234;14266.215358155298;14295.428182872673;14298.57104286873;14299;776.5224387001981;948.4451595816456;978.1441238915949;978.1538968792015;982.5185659027061;958.0515289863021;969.6024629901742;970.6360114956891;971.2909576009517;974.8537719342085;827.664795757719;1024.8718910136233;1026.8151732688113;1027.085893554737;1029.2229366828035;1000.5647543318644;1030.2685093583564;1030.3920686794827;1031.1802117316752;1033.607229252879;775.1086250811555;775.6897241103547;777.3470284960661;777.3858651665178;777.4713195027379;13633.795493067611;14010;15118.773218401733;15212.163331016789;15224.771628425582;12704.428300726397;13048.217106973612;13189.802152967713;13309.334533273297;13546.187228766306;9149.451032938045;10063.5471403787;10167.542460589291;10282.485875706185;10515.369077855354;3547.609762926076;3569.5359603251673;3711.6659500645073;3763.849446022156;3844.0966372902626;88332.55833720854;88804;88956;89076.6638500423;89262;8583.570821458901;9051.502167380857;9485.241180705527;9501.524923753785;10394.584216631401;21387.46531336729;21570.35288941334;21631.134754609953;21636.675449868264;21695;7520.871869219552;7562.546247225184;7562.886556701654;7803.102643195992;7844.921550784533;4147.398627199039;4218.894527636834;4231.217224813419;4404.7357158570585;4481.35020422037;3547.7161827053774;3682.668559829628;3687.9631203688155;3733.775973441602;3740.2706472238197;650.7657243392375;656.3370995294787;659.475716805141;671.4225765841372;674.2145400608237;561.3600495435207;571.6055921414211;594.0791772752303;616;627.3193584960369;7831.138574756772;8526.658933642584;8831.514266715263;8868.246199073152;8894.777630559158;606.9484093852072;621.996890015547;624.0701354981136;634.1565717595587;635.0505993539688;3765.962340376616;4003.099302656937;4036.1120553478186;4081.653059489977;4106.07613287008;89059.66410503848;91854.54072729727;91861.54069229745;92128;92237;74409;75086.62456687681;76517;78390.60804696054;81485;63134.68432657807;63789.681051594445;65099.674501628135;71953.2804671957;73348.63325683337;8421.789455263668;8430.325573954191;8593;8727.170918762708;8734.213920747163;47039;47701.76149119232;48235.79410514765;50628.987420250654;51186.74406627943;51761.636360015495;62685;67698.6460270782;69580;69720.65139674269;10768.138548916224;11168.162387820783;11175.441227938409;11331.830022549504;11432.371219583003;11854.348010859485;11960.342181180118;12137.332446715516;12161.939190303992;12521.812172817234;11422.200445969034;11517.654470365902;11524;11689.649310520697;11712.88287117118;11199.832002519806;11565.884341156818;11598.304101753922;11604.825927611262;12100.213486123374;11221.326720396803;11257.099432045581;11367.976882080653;11565.884341156481;11624.941875290908;7704.537727736129;9385.343025988115;9467.053294670477;9580.95209523948;9706.077922597342;9191.415348014147;9331.953340233254;9361.906380936376;9374.156325930699;9409.341346105706 18:17:26 INFO - PROCESS | 1662 | __end_tp_report 18:17:26 INFO - PROCESS | 1662 | __start_cc_report 18:17:26 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1253 18:17:26 INFO - PROCESS | 1662 | __end_cc_report 18:17:26 INFO - PROCESS | 1662 | __startTimestamp1468199846540__endTimestamp 18:17:26 INFO - PROCESS | 1662 | 18:17:26 INFO - PROCESS | 1662 | ------- Summary: start ------- 18:17:26 INFO - PROCESS | 1662 | Number of tests: 6 18:17:26 INFO - PROCESS | 1662 | 18:17:26 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5926.11 Median:6215.94 stddev:649.98 (10.5%) stddev-sans-first:618.23 18:17:26 INFO - PROCESS | 1662 | Values: 3291.3 3636.3 3639.4 3640.9 3735.5 5985.7 6012.3 6017.6 6020.9 6069.7 6024.7 6043.5 6059.5 6064.1 6080.7 5270.6 5341.5 5404.5 5436.8 5481.2 5402.0 5452.2 5454.2 5465.5 5467.8 4528.4 5174.6 5367.8 5399.3 5461.2 5433.2 5480.5 5482.1 5517.7 5521.6 5285.5 5308.2 5336.1 5368.8 5397.1 5862.1 5895.0 5920.7 5963.8 6048.2 6270.6 6293.0 6310.7 6314.9 6321.7 6200.6 6319.6 6386.3 6401.8 6408.3 6075.6 6077.9 6121.3 6123.5 6129.2 6482.0 6495.2 6496.0 6513.7 6529.3 6221.4 6231.0 6321.3 6343.3 6344.2 6318.1 6333.3 6339.6 6340.6 6347.5 6249.8 6303.0 6307.0 6322.7 6367.2 5097.5 6237.0 6258.9 6302.4 6338.8 5052.8 5081.4 5083.9 5085.7 5096.3 4591.6 4599.8 4602.7 4606.5 4665.6 5968.3 6031.4 6079.0 6112.2 6130.4 6329.7 6338.9 6347.6 6348.1 6359.1 6038.9 6042.5 6046.3 6051.4 6068.4 4721.7 5475.8 5525.5 5560.9 5643.4 6576.7 6645.9 6669.6 6674.1 6681.4 6262.1 6408.3 6413.2 6420.5 6428.6 6543.2 6594.2 6627.4 6641.2 6649.3 6286.6 6287.3 6311.8 6324.4 6325.2 6108.6 6313.4 6316.7 6317.3 6403.0 6249.1 6283.7 6297.5 6311.7 6320.1 6283.9 6293.6 6296.9 6297.4 6303.3 6105.1 6184.9 6239.3 6250.2 6284.9 5167.2 6215.9 6282.8 6291.2 6298.4 6166.8 6251.5 6253.6 6254.8 6262.0 18:17:26 INFO - PROCESS | 1662 | 18:17:26 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:63860.56 Median:18829.44 stddev:137717.69 (731.4%) stddev-sans-first:138021.91 18:17:26 INFO - PROCESS | 1662 | Values: 521.1 531.8 580.6 606.2 610.2 26089.0 33653.0 33714.7 33721.7 33835.7 26391.0 39215.4 39515.0 39542.6 39585.2 9834.9 10063.6 10065.9 10081.6 10083.2 9276.4 9901.2 10023.8 10035.0 10038.8 11996.2 12241.7 12252.2 12256.0 12282.9 11895.5 12051.9 12063.8 12064.1 12101.4 11781.0 11905.9 11916.2 11917.9 11918.6 41154.8 41316.8 41637.8 43567.8 43628.8 3289.4 3378.8 3420.1 3421.2 3437.1 27744.9 28020.4 29119.0 29872.9 30164.1 2783.9 2807.2 2815.9 2846.9 2904.1 342760.0 348523.0 349929.3 350252.0 351413.0 37883.4 38802.0 63787.4 64280.4 68312.0 37602.8 37704.8 37896.8 37956.8 37990.4 16423.4 16607.4 16616.8 16617.1 16660.1 13938.0 14006.4 14009.2 14025.4 14041.9 8181.6 8314.3 8553.6 8617.3 8751.0 3093.6 3513.7 3565.4 3568.4 3575.0 756.5 786.7 1037.9 1090.4 1130.2 28645.4 29887.9 29952.7 30328.5 30983.7 965.8 1161.6 1170.1 1175.8 1183.0 15352.8 15729.8 15845.0 15872.5 15990.8 623275.0 631499.0 632335.0 632748.0 633133.0 66961.0 67175.7 67449.7 67452.0 67488.0 1236.4 1435.5 1536.5 1549.4 1607.3 26025.0 29889.9 31177.8 32051.8 32565.0 56909.0 57390.0 57838.8 58141.4 58176.1 521529.0 525870.0 526317.0 526688.0 528600.0 25435.6 29100.1 29421.7 29546.9 29815.8 45023.8 45590.5 46644.5 46913.3 47434.5 37019.3 37105.8 39569.8 39757.2 40387.6 34350.8 37386.7 38313.8 39417.8 39988.0 38992.8 39342.2 40596.8 40926.4 41852.4 32444.0 34237.7 34796.7 34828.8 34948.8 19996.8 26133.0 26141.3 26405.1 26668.5 18:17:26 INFO - PROCESS | 1662 | 18:17:26 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:35563.93 Median:27820.24 stddev:106950.10 (384.4%) stddev-sans-first:107217.56 18:17:26 INFO - PROCESS | 1662 | Values: 416.9 418.5 418.8 419.9 432.2 20794.7 21326.6 21537.2 22665.5 23355.8 20077.5 21784.7 21900.2 22631.2 22708.4 6083.1 6236.8 6279.3 6279.6 6292.1 4555.7 4781.6 4827.5 6234.6 6310.8 6901.5 6904.5 6908.9 6911.8 6922.3 6659.3 6756.8 6826.1 6861.7 6876.5 4083.5 5714.1 6027.7 6036.5 6044.6 26880.9 27566.0 28074.4 28083.0 28203.6 2234.7 2274.0 2362.8 2431.8 2474.9 15536.0 15797.0 15853.0 16024.0 16198.0 1776.4 1782.8 1791.9 1818.8 1862.3 74131.6 89405.7 92084.0 92937.0 93243.1 10867.6 10997.2 11577.2 11905.9 12001.3 15630.7 15658.3 15698.5 15722.4 15774.3 7867.3 9097.9 9101.8 9260.7 9343.2 7556.7 9245.0 9253.0 9269.4 9379.2 4404.9 4569.1 4729.0 4786.6 4789.8 1995.1 2034.4 2040.2 2053.5 2069.4 900.7 984.2 999.4 1012.1 1054.2 19971.0 20943.4 20985.8 21025.3 21169.0 840.1 965.4 1006.5 1031.9 1060.1 8762.3 8838.7 10994.7 11159.1 11189.6 634328.0 637591.0 645344.0 660343.0 663206.0 116739.4 120076.0 120570.0 121397.0 123659.4 1045.6 1060.0 1060.4 1075.7 1077.0 18191.9 18443.9 18446.8 19799.0 19854.3 49076.8 49862.0 50702.5 50841.0 52041.4 71643.1 72533.6 74126.3 75000.6 75285.0 8064.2 8253.3 8383.9 8396.6 8462.2 9173.7 11730.8 12382.9 12906.8 13120.6 10840.3 11043.5 11224.2 11320.3 11434.2 10925.0 10990.7 11070.7 11363.9 11581.8 6065.2 7142.5 10519.2 11488.9 11592.6 8239.0 8329.3 8339.8 8493.2 8510.1 9765.7 11414.7 11418.8 11740.6 11751.8 18:17:26 INFO - PROCESS | 1662 | 18:17:26 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5875.21 Median:5151.83 stddev:3250.24 (63.1%) stddev-sans-first:3242.70 18:17:26 INFO - PROCESS | 1662 | Values: 1497.5 1628.3 1658.8 1774.0 1954.7 8370.5 8836.6 9030.7 9034.6 9046.7 8881.3 9151.4 9152.8 9162.5 9173.8 2538.0 2577.0 2577.8 2578.1 2579.9 2425.1 2425.4 2434.5 2439.9 2466.0 2533.8 2557.0 2574.5 2576.2 2579.1 2508.9 2529.3 2529.8 2564.6 2574.1 2102.4 2558.0 2567.6 2570.9 2582.6 8894.4 9189.3 9307.3 9308.9 9310.2 5636.4 5730.7 5733.3 5804.2 5809.6 3673.2 3722.0 3796.3 3803.4 3820.0 1054.1 1074.6 1076.5 1092.7 1118.5 12289.8 12418.8 12517.9 12580.0 12582.6 4767.3 4807.1 4815.6 4866.4 4884.1 9724.3 9742.4 9754.4 9758.2 9787.7 5588.5 5590.7 5591.6 5601.0 5602.5 4649.5 4818.2 5022.8 5137.9 5175.4 4000.6 4005.4 4083.9 4090.2 4137.2 1270.7 1283.7 1286.4 1297.9 1299.5 962.6 987.7 998.4 1008.6 1021.4 5890.3 6073.4 6098.3 6165.5 6446.3 873.3 992.0 992.2 1028.7 1052.9 3986.3 4067.3 4100.1 4166.7 4184.3 10855.7 11242.6 11245.6 11408.8 11411.7 11133.7 11233.5 11252.2 11252.4 11272.4 8321.3 8348.2 8577.0 8664.3 8675.7 5332.9 5837.2 5975.9 6054.6 6139.2 7939.6 9399.7 9675.6 9830.0 9846.7 8300.8 9646.5 9864.2 10018.8 10187.4 7117.8 7281.3 7782.9 7991.3 8215.1 8279.0 8628.6 8645.1 8661.4 8662.3 7449.2 7580.0 7632.2 7665.8 7716.6 7087.8 7290.2 7436.4 7568.1 7635.1 6443.4 7206.1 7252.1 7253.6 7254.8 5012.0 5033.7 5135.0 5201.4 5234.6 4747.4 4957.8 4964.3 5165.7 5218.2 18:17:26 INFO - PROCESS | 1662 | 18:17:26 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5981.18 Median:6818.14 stddev:1901.67 (27.9%) stddev-sans-first:1859.03 18:17:26 INFO - PROCESS | 1662 | Values: 326.4 331.5 334.0 350.7 354.7 6796.7 6835.1 6851.6 6860.0 6869.0 6094.8 6732.7 6756.9 6777.3 6784.7 4333.3 4850.8 4895.0 4937.8 4992.3 4883.2 4963.6 4972.4 4987.1 4991.3 4929.2 4929.6 4929.7 4949.3 4960.8 4707.9 4890.4 4899.1 4899.7 4909.1 4177.5 4266.7 4270.6 4282.6 4297.1 6375.4 6404.0 6467.0 6471.3 6500.6 5938.4 5941.5 5954.5 5968.1 5973.6 6949.1 7238.4 7249.1 7257.6 7270.1 4819.3 4833.5 4853.4 4863.8 4866.2 7492.9 7680.4 7693.7 7699.9 7710.6 4966.1 4982.3 4984.6 4996.6 4996.8 5058.4 5063.4 5069.3 5070.1 5074.5 5745.5 5958.2 5959.7 5965.2 5974.1 7250.3 7298.0 7314.5 7328.3 7329.6 1722.0 1753.1 1754.9 1757.1 1759.0 848.5 975.2 1099.7 1109.8 1141.5 6650.4 6805.2 6807.1 6815.6 6886.9 7339.6 7451.0 7453.9 7632.8 7638.0 6942.2 6965.4 6971.7 6976.5 6980.4 3944.9 4012.2 4015.6 4016.0 4019.1 7245.3 7673.7 7958.2 7960.6 7980.7 7811.0 7857.0 7865.7 7872.0 7885.0 7967.4 7974.2 7980.8 8007.4 8010.7 7325.7 7599.2 7600.5 7608.7 7616.8 7425.6 7481.4 7482.7 7489.7 7503.1 6405.3 7914.2 7966.1 7969.8 7977.5 4971.4 4978.3 4982.9 4984.5 4997.5 7231.5 7576.2 7578.3 7585.5 7596.3 6997.3 7011.8 7286.1 7339.1 7361.3 7308.5 7312.8 7328.1 7330.1 7342.6 5798.4 7186.0 7195.5 7199.1 7201.4 6169.6 7045.9 7284.7 7309.5 7314.3 6820.7 6961.7 6996.4 7162.3 7186.2 18:17:26 INFO - PROCESS | 1662 | 18:17:26 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:18362.10 Median:566.48 stddev:25806.22 (4555.5%) stddev-sans-first:25844.19 18:17:26 INFO - PROCESS | 1662 | Values: 609.8 686.2 693.3 770.1 773.6 15363.4 15548.5 15618.8 15648.0 15652.9 14203.3 14266.2 14295.4 14298.6 14299.0 776.5 948.4 978.1 978.2 982.5 958.1 969.6 970.6 971.3 974.9 827.7 1024.9 1026.8 1027.1 1029.2 1000.6 1030.3 1030.4 1031.2 1033.6 775.1 775.7 777.3 777.4 777.5 13633.8 14010.0 15118.8 15212.2 15224.8 12704.4 13048.2 13189.8 13309.3 13546.2 9149.5 10063.5 10167.5 10282.5 10515.4 3547.6 3569.5 3711.7 3763.8 3844.1 88332.6 88804.0 88956.0 89076.7 89262.0 8583.6 9051.5 9485.2 9501.5 10394.6 21387.5 21570.4 21631.1 21636.7 21695.0 7520.9 7562.5 7562.9 7803.1 7844.9 4147.4 4218.9 4231.2 4404.7 4481.4 3547.7 3682.7 3688.0 3733.8 3740.3 650.8 656.3 659.5 671.4 674.2 561.4 571.6 594.1 616.0 627.3 7831.1 8526.7 8831.5 8868.2 8894.8 606.9 622.0 624.1 634.2 635.1 3766.0 4003.1 4036.1 4081.7 4106.1 89059.7 91854.5 91861.5 92128.0 92237.0 74409.0 75086.6 76517.0 78390.6 81485.0 63134.7 63789.7 65099.7 71953.3 73348.6 8421.8 8430.3 8593.0 8727.2 8734.2 47039.0 47701.8 48235.8 50629.0 51186.7 51761.6 62685.0 67698.6 69580.0 69720.7 10768.1 11168.2 11175.4 11331.8 11432.4 11854.3 11960.3 12137.3 12161.9 12521.8 11422.2 11517.7 11524.0 11689.6 11712.9 11199.8 11565.9 11598.3 11604.8 12100.2 11221.3 11257.1 11368.0 11565.9 11624.9 7704.5 9385.3 9467.1 9581.0 9706.1 9191.4 9332.0 9361.9 9374.2 9409.3 18:17:26 INFO - PROCESS | 1662 | -------- Summary: end -------- 18:17:26 INFO - PROCESS | 1662 | 18:17:27 INFO - TEST-INFO | 1662: exit 0 18:17:27 INFO - TEST-OK | dromaeo_css | took 1113143ms 18:17:27 INFO - TEST-START | kraken 18:17:27 INFO - operating with platform_type : mac_ 18:17:27 INFO - Initialising browser for kraken test... 18:17:27 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpFa8APV/profile http://localhost:49275/getInfo.html) 18:17:29 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 18:17:29 INFO - PROCESS | 1669 | colorDepth:24 18:17:29 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 18:17:29 INFO - PROCESS | 1669 | __metrics 18:17:29 INFO - PROCESS | 1669 | 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" 18:17:29 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:17:30 INFO - TEST-INFO | 1669: exit 0 18:17:30 INFO - Browser initialized. 18:17:30 INFO - Running cycle 1/1 for kraken test... 18:17:30 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/tmpFa8APV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:17:42 INFO - PROCESS | 1672 | RSS: Main: 161206272 18:17:42 INFO - PROCESS | 1672 | 18:17:44 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 18:17:45 INFO - PROCESS | 1672 | RSS: Main: 224309248 18:17:45 INFO - PROCESS | 1672 | 18:17:48 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 18:17:48 INFO - PROCESS | 1672 | RSS: Main: 239681536 18:17:48 INFO - PROCESS | 1672 | 18:17:51 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 18:17:52 INFO - PROCESS | 1672 | RSS: Main: 325754880 18:17:52 INFO - PROCESS | 1672 | 18:17:54 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 18:17:55 INFO - PROCESS | 1672 | RSS: Main: 231563264 18:17:55 INFO - PROCESS | 1672 | 18:17:57 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:17:57 INFO - PROCESS | 1672 | RSS: Main: 232927232 18:17:57 INFO - PROCESS | 1672 | 18:18:00 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 18:18:01 INFO - PROCESS | 1672 | RSS: Main: 271134720 18:18:01 INFO - PROCESS | 1672 | 18:18:04 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 18:18:04 INFO - PROCESS | 1672 | RSS: Main: 270336000 18:18:04 INFO - PROCESS | 1672 | 18:18:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 18:18:08 INFO - PROCESS | 1672 | RSS: Main: 269537280 18:18:08 INFO - PROCESS | 1672 | 18:18:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:18:10 INFO - PROCESS | 1672 | RSS: Main: 259940352 18:18:10 INFO - PROCESS | 1672 | 18:18:12 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:18:12 INFO - PROCESS | 1672 | RSS: Main: 267005952 18:18:12 INFO - PROCESS | 1672 | 18:18:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:18:15 INFO - PROCESS | 1672 | RSS: Main: 271327232 18:18:15 INFO - PROCESS | 1672 | 18:18:18 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:18:18 INFO - PROCESS | 1672 | RSS: Main: 279011328 18:18:18 INFO - PROCESS | 1672 | 18:18:21 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:18:22 INFO - PROCESS | 1672 | RSS: Main: 275476480 18:18:22 INFO - PROCESS | 1672 | 18:18:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 18:18:24 INFO - PROCESS | 1672 | RSS: Main: 336543744 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | __start_tp_report 18:18:24 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 18:18:24 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 18:18:24 INFO - PROCESS | 1672 | |i|pagename|runs| 18:18:24 INFO - PROCESS | 1672 | |0;ai-astar;114;113;118;112;111;111;112;110;111;133 18:18:24 INFO - PROCESS | 1672 | |1;audio-beat-detection;156;120;143;166;119;140;118;151;161;146 18:18:24 INFO - PROCESS | 1672 | |2;audio-dft;186;185;186;185;183;183;183;183;181;183 18:18:24 INFO - PROCESS | 1672 | |3;audio-fft;70;78;78;156;56;55;55;61;132;56 18:18:24 INFO - PROCESS | 1672 | |4;audio-oscillator;93;91;140;79;79;90;128;80;77;127 18:18:24 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;87;85;87;85;86;85;88;85;88;84 18:18:24 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;92;92;93;93;92;106;149;117;92 18:18:24 INFO - PROCESS | 1672 | |7;imaging-desaturate;84;83;85;84;82;83;86;84;83;110 18:18:24 INFO - PROCESS | 1672 | |8;json-parse-financial;58;62;65;64;64;63;65;81;58;55 18:18:24 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;61;63;67;67;74;75;68;67;72;65 18:18:24 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;81;79;79;79;77;81;77;78;81;83 18:18:24 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;139;146;112;148;146;132;124;126;143;139 18:18:24 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;163;165;163;162;157;156;162;165;158;158 18:18:24 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;65;67;66;66;68;67;65;65;66;67 18:18:24 INFO - PROCESS | 1672 | __end_tp_report 18:18:24 INFO - PROCESS | 1672 | __start_cc_report 18:18:24 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1934 18:18:24 INFO - PROCESS | 1672 | __end_cc_report 18:18:24 INFO - PROCESS | 1672 | __startTimestamp1468199904146__endTimestamp 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | ------- Summary: start ------- 18:18:24 INFO - PROCESS | 1672 | Number of tests: 14 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:114.50 Median:112.50 stddev:6.88 (6.1%) stddev-sans-first:7.30 18:18:24 INFO - PROCESS | 1672 | Values: 114.0 113.0 118.0 112.0 111.0 111.0 112.0 110.0 111.0 133.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:148.50 stddev:17.71 (11.9%) stddev-sans-first:18.05 18:18:24 INFO - PROCESS | 1672 | Values: 156.0 120.0 143.0 166.0 119.0 140.0 118.0 151.0 161.0 146.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.62 (0.9%) stddev-sans-first:1.51 18:18:24 INFO - PROCESS | 1672 | Values: 186.0 185.0 186.0 185.0 183.0 183.0 183.0 183.0 181.0 183.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:79.70 Median:58.50 stddev:35.51 (60.7%) stddev-sans-first:37.49 18:18:24 INFO - PROCESS | 1672 | Values: 70.0 78.0 78.0 156.0 56.0 55.0 55.0 61.0 132.0 56.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:79.50 stddev:23.87 (30.0%) stddev-sans-first:25.24 18:18:24 INFO - PROCESS | 1672 | Values: 93.0 91.0 140.0 79.0 79.0 90.0 128.0 80.0 77.0 127.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:86.50 stddev:1.41 (1.6%) stddev-sans-first:1.45 18:18:24 INFO - PROCESS | 1672 | Values: 87.0 85.0 87.0 85.0 86.0 85.0 88.0 85.0 88.0 84.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:92.00 stddev:18.54 (20.1%) stddev-sans-first:19.38 18:18:24 INFO - PROCESS | 1672 | Values: 93.0 92.0 92.0 93.0 93.0 92.0 106.0 149.0 117.0 92.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:84.00 stddev:8.37 (10.0%) stddev-sans-first:8.83 18:18:24 INFO - PROCESS | 1672 | Values: 84.0 83.0 85.0 84.0 82.0 83.0 86.0 84.0 83.0 110.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:64.00 stddev:7.04 (11.0%) stddev-sans-first:7.18 18:18:24 INFO - PROCESS | 1672 | Values: 58.0 62.0 65.0 64.0 64.0 63.0 65.0 81.0 58.0 55.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:67.50 stddev:4.56 (6.8%) stddev-sans-first:4.09 18:18:24 INFO - PROCESS | 1672 | Values: 61.0 63.0 67.0 67.0 74.0 75.0 68.0 67.0 72.0 65.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:80.00 stddev:1.96 (2.4%) stddev-sans-first:2.00 18:18:24 INFO - PROCESS | 1672 | Values: 81.0 79.0 79.0 79.0 77.0 81.0 77.0 78.0 81.0 83.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:135.50 Median:141.00 stddev:11.76 (8.3%) stddev-sans-first:12.40 18:18:24 INFO - PROCESS | 1672 | Values: 139.0 146.0 112.0 148.0 146.0 132.0 124.0 126.0 143.0 139.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:162.50 stddev:3.35 (2.1%) stddev-sans-first:3.46 18:18:24 INFO - PROCESS | 1672 | Values: 163.0 165.0 163.0 162.0 157.0 156.0 162.0 165.0 158.0 158.0 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.03 (1.6%) stddev-sans-first:1.00 18:18:24 INFO - PROCESS | 1672 | Values: 65.0 67.0 66.0 66.0 68.0 67.0 65.0 65.0 66.0 67.0 18:18:24 INFO - PROCESS | 1672 | -------- Summary: end -------- 18:18:24 INFO - PROCESS | 1672 | 18:18:24 INFO - TEST-INFO | 1672: exit 0 18:18:24 INFO - TEST-OK | kraken | took 57825ms 18:18:24 INFO - SUITE-END | took 1170s 18:18:25 INFO - Completed test suite (00:19:31) 18:18:25 INFO - javascript benchmark 18:18:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3291.2759192977546, 3636.2727454509104, 3639.3813051781194, 3640.908977275568, 3735.533058367704, 5985.70071496425, 6012.3085845127825, 6017.578769486137, 6020.879821485495, 6069.726862291196, 6024.69876506174, 6043.486303664183, 6059.545534084939, 6064.090386442036, 6080.665563394023, 5270.578353731692, 5341.519263266296, 5404.4595540446035, 5436.83689489314, 5481.150421684645, 5402.0, 5452.2094296327205, 5454.236406903036, 5465.535429488498, 5467.753951072192, 4528.366028755977, 5174.611904107188, 5367.785288588452, 5399.271098401705, 5461.1535212042, 5433.1850222466355, 5480.534154596824, 5482.068048431776, 5517.724113794295, 5521.641093328961, 5285.524302812727, 5308.177232528926, 5336.092864213093, 5368.8389348319615, 5397.109476936301, 5862.12068189775, 5894.970525147347, 5920.703964801743, 5963.7912673056235, 6048.183495228176, 6270.592411493284, 6292.968535157295, 6310.684465776692, 6314.93685063148, 6321.683915804238, 6200.627962322275, 6319.5576309658345, 6386.2975072741965, 6401.839954001187, 6408.263049749292, 6075.634845698943, 6077.908831367489, 6121.265448146251, 6123.510119190454, 6129.233845769279, 6481.967590162019, 6495.1556297681, 6496.025596160519, 6513.674316284167, 6529.2817790043055, 6221.408966148299, 6231.003039513746, 6321.273053598895, 6343.27052388972, 6344.206974128234, 6318.115463835067, 6333.303336632966, 6339.556231063873, 6340.619562826245, 6347.492200623939, 6249.750009999549, 6303.0, 6306.990881458944, 6322.683865806691, 6367.235931688227, 5097.46476619957, 6236.968815155895, 6258.906116408258, 6302.432781049636, 6338.778142765073, 5052.823151189691, 5081.390233171969, 5083.87290317745, 5085.720285384339, 5096.337476128079, 4591.609713174417, 4599.770011499479, 4602.7468489233415, 4606.539346065434, 4665.626749860003, 5968.253968253968, 6031.366706495843, 6078.969605151946, 6112.229929638693, 6130.367562715059, 6329.683515824282, 6338.904916426257, 6347.619142851444, 6348.079528468346, 6359.077933699587, 6038.939610603926, 6042.516598672096, 6046.304674962347, 6051.394860513913, 6068.362821903725, 4721.669483136147, 5475.753591088331, 5525.502704756591, 5560.944390556043, 5643.351014633287, 6576.736930522821, 6645.867082658417, 6669.599824010575, 6674.098996635392, 6681.432078273401, 6262.091996660368, 6408.327125651926, 6413.230412350548, 6420.518461115528, 6428.55000149985, 6543.247526534509, 6594.208694956636, 6627.403533681992, 6641.203055633355, 6649.301823308677, 6286.622802631857, 6287.339829317856, 6311.779087731908, 6324.367563243639, 6325.177726895474, 6108.645631960618, 6313.40022697843, 6316.652584107918, 6317.273513546001, 6403.0, 6249.0626406039455, 6283.748650053946, 6297.52768542352, 6311.652859092794, 6320.1151838743535, 6283.937160628335, 6293.622382657055, 6296.874062518632, 6297.370262973666, 6303.306636270098, 6105.084237364431, 6184.907226391518, 6239.344868788893, 6250.249970003447, 6284.937150628435, 5167.199084141964, 6215.9378406215355, 6282.811515654537, 6291.213598300213, 6298.401651843067, 6166.81499555014, 6251.46862516673, 6253.593516421418, 6254.843628909313, 6261.96869015652], "value": 5886.525362099465, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.1401188039735, 531.8218396837062, 580.625496554722, 606.1634943777586, 610.2280615021995, 26089.0, 33652.99999999997, 33714.66285337152, 33721.66278337216, 33835.66164338359, 26391.0, 39215.41176882349, 39515.0121246969, 39542.60457395425, 39585.20829583407, 9834.852477212848, 10063.64777232797, 10065.899341006607, 10081.596736130547, 10083.243756718239, 9276.397034192756, 9901.20790336775, 10023.799524009515, 10035.0, 10038.799224015514, 11996.160268781188, 12241.693957651085, 12252.203606765575, 12256.0, 12282.877171228263, 11895.464704088294, 12051.93974030133, 12063.758724825542, 12064.095192860483, 12101.39493025354, 11780.998615117725, 11905.880941190564, 11916.165868389216, 11917.94041029798, 11918.582849600223, 41154.79422602868, 41316.793416033026, 41637.750867474024, 43567.782161088995, 43628.781856090514, 3289.407906576815, 3378.763486555942, 3420.1278673937986, 3421.230223199785, 3437.123533498942, 27744.8902043918, 28020.43959120806, 29119.0, 29872.850635746683, 30164.095077147722, 2783.9304017399522, 2807.2420446479337, 2815.9155225343275, 2846.8576571171357, 2904.07069737684, 342760.0, 348522.99999999744, 349929.25035374914, 350252.0, 351413.0, 37883.43174852379, 38802.0, 63787.36212637907, 64280.35719642837, 68312.0, 37602.811985939894, 37704.811475942995, 37896.81051594725, 37956.810215948746, 37990.43014354787, 16423.425180118877, 16607.41874034403, 16616.83383166153, 16617.08606026656, 16660.083695396628, 13938.024338296222, 14006.369713362923, 14009.229492377814, 14025.438982440792, 14041.85958140426, 8181.590920453954, 8314.334853211729, 8553.572321383906, 8617.267532259702, 8751.0, 3093.6442309134286, 3513.683768460851, 3565.358235517632, 3568.3576956148145, 3575.0, 756.5385115079797, 786.7364432914923, 1037.9481025948671, 1090.3675867996542, 1130.2032067392634, 28645.427091458056, 29887.850560747058, 29952.700472995428, 30328.54507182394, 30983.69016309853, 965.82615129276, 1161.6050542815483, 1170.0814860334597, 1175.8177482490228, 1183.0180949811636, 15352.769708454382, 15729.764053539206, 15845.0, 15872.477700051237, 15990.76013859793, 623275.0, 631499.0, 632335.0, 632748.0, 633133.0, 66961.0, 67175.66412167907, 67449.66275168593, 67451.98822017673, 67488.0, 1236.3879879459726, 1435.5047508609514, 1536.5006372928842, 1549.3725041358268, 1607.284758282553, 26025.0, 29889.850550747105, 31177.8441107793, 32051.83974080208, 32565.0, 56909.0, 57390.0, 57838.84322313446, 58141.41858581529, 58176.127358088816, 521529.0, 525870.0, 526317.0, 526688.0, 528600.0, 25435.61846572266, 29100.12699619015, 29421.705782942754, 29546.852265738533, 29815.80736770505, 45023.774881125384, 45590.544094559955, 46644.53355466402, 46913.296300556205, 47434.52565474439, 37019.25961480809, 37105.81447092747, 39569.80215098906, 39757.2048559033, 40387.596124039555, 34350.82824585861, 37386.69146579857, 38313.808430957666, 39417.802910985265, 39988.0, 38992.80503597464, 39342.2131557373, 40596.79701601473, 40926.38610420906, 41852.3722144162, 32444.026679199662, 34237.657623423445, 34796.65203348036, 34828.82585587056, 34948.82525587457, 19996.800031999494, 26133.0, 26141.346466338495, 26405.075822346895, 26668.466630667666], "value": 19116.329047249572, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.94788151481066, 418.4580967646897, 418.78851180154015, 419.9265128602494, 432.1551367077364, 20794.688079678817, 21326.573468530638, 21537.24619638313, 22665.54668906625, 23355.76644233557, 20077.498062548442, 21784.673229901564, 21900.23349182779, 22631.207907723234, 22708.432289192777, 6083.148359229712, 6236.812895613116, 6279.340669229734, 6279.591826531284, 6292.119103325538, 4555.681102322822, 4781.6413768967295, 4827.517248275162, 6234.625922444668, 6310.842228944291, 6901.482388820833, 6904.516683832134, 6908.896366554506, 6911.758088466905, 6922.307769223063, 6659.334066593302, 6756.8310792230095, 6826.112605361272, 6861.725530978755, 6876.518643694943, 4083.530394004698, 5714.1428785682065, 6027.728752206117, 6036.486898613623, 6044.576879618428, 26880.86559567209, 27566.0351887685, 28074.438511229866, 28083.0170944016, 28203.57694634582, 2234.653628687556, 2274.0108052996834, 2362.7519110493495, 2431.7811396974357, 2474.91337803176, 15535.990160639521, 15797.0, 15853.048817071012, 16023.958442701187, 16198.0, 1776.4493007167819, 1782.777152855893, 1791.9193636286398, 1818.8363047325672, 1862.2923289150037, 74131.62934185349, 89405.65891511568, 92084.0, 92937.0, 93243.06756932412, 10867.619633312954, 10997.175211859143, 11577.189596728316, 11905.880941190651, 12001.339926303963, 15630.687386252439, 15658.295376707847, 15698.450554230547, 15722.371105155666, 15774.290156942965, 7867.331276841533, 9097.863532047024, 9101.77245568866, 9260.675876344296, 9343.159115679622, 7556.697732090654, 9245.029271926485, 9253.0, 9269.397489163182, 9379.202767764817, 4404.942813724683, 4569.063342014879, 4729.0, 4786.569208771228, 4789.760511974457, 1995.1121750821035, 2034.4334862156056, 2040.2247146084394, 2053.5276886316233, 2069.3584988653565, 900.7297810656777, 984.2372161574693, 999.3953658036928, 1012.1447376966452, 1054.1566746602687, 19971.0, 20943.37169884906, 20985.79014209869, 21025.26411575618, 21169.0, 840.1304649687544, 965.4062751407834, 1006.5118417567473, 1031.896810318962, 1060.0724366179593, 8762.342824288076, 8838.690645827493, 10994.670159895217, 11159.051480624237, 11189.608363707232, 634328.0, 637591.0, 645344.0, 660343.0, 663206.0, 116739.41630291964, 120076.0, 120570.0, 121397.0, 123659.38170309091, 1045.602670985036, 1060.0, 1060.390275591538, 1075.7310672331917, 1077.0145317034983, 18191.908485490912, 18443.907780461013, 18446.81553184451, 19799.01004949718, 19854.305099321453, 49076.75461622669, 49862.0, 50702.49297506978, 50841.0, 52041.35710500229, 71643.13427463052, 72533.63733181512, 74126.25873741193, 75000.62499687467, 75285.0, 8064.15326390744, 8253.298469630148, 8383.916160838313, 8396.580170991549, 8462.153784621489, 9173.678921237724, 11730.824037639271, 12382.938085309515, 12906.806397904227, 13120.60638180856, 10840.349579025284, 11043.503042362952, 11224.15818813576, 11320.26418282809, 11434.199606027498, 10925.0, 10990.670279891616, 11070.667879963614, 11363.92042755937, 11581.768364632491, 6065.211522502046, 7142.464425129212, 10519.21105917044, 11488.885111148782, 11592.608886933622, 8238.958805205935, 8329.333653307842, 8339.83320333602, 8493.150684931705, 8510.063892971892, 9765.658201962897, 11414.657560273205, 11418.828717569077, 11740.647780566596, 11751.823722644338], "value": 9822.369263335893, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1497.5282785922427, 1628.2998310726387, 1658.842409971053, 1773.9822601773988, 1954.7458830352061, 8370.497770133796, 8836.602352894119, 9030.72907812765, 9034.59344329505, 9046.728598142057, 8881.333899957497, 9151.359404841663, 9152.771180720485, 9162.496062716547, 9173.77065573361, 2537.9873100634472, 2577.0078519769836, 2577.8066645001604, 2578.1105518596055, 2579.85810780407, 2425.126954296452, 2425.405775584984, 2434.549608322457, 2439.9024039038504, 2466.025919761693, 2533.8353007054484, 2557.0, 2574.5237131130707, 2576.227131860444, 2579.1102069785893, 2508.8745562721974, 2529.3170843872053, 2529.8355606885493, 2564.5768448206118, 2574.060467929202, 2102.400815767499, 2557.9872100639377, 2567.550678631251, 2570.9485810283877, 2582.5609646359976, 8894.377393582452, 9189.264858811279, 9307.278153541549, 9308.8603670945, 9310.208632266265, 5636.436356364372, 5730.68481233532, 5733.312002559678, 5804.216430781875, 5809.622374545684, 3673.2286219893854, 3721.9627803721887, 3796.335641262768, 3803.3724435468116, 3819.9618003819883, 1054.0882136951482, 1074.570171931226, 1076.5155679943978, 1092.6940456672144, 1118.546988469671, 12289.815652765215, 12418.75162496745, 12517.937410312981, 12580.0, 12582.559610413591, 4767.332573439721, 4807.145352028328, 4815.6388270879825, 4866.391701037371, 4884.072026314989, 9724.319297649237, 9742.415455072576, 9754.414735115915, 9758.219342452587, 9787.706368808947, 5588.497035266845, 5590.748416321276, 5591.552675786009, 5601.0, 5602.523785478198, 4649.488556258809, 4818.241341910248, 5022.84931452057, 5137.922931156036, 5175.430702622709, 4000.5599384067727, 4005.379166229181, 4083.9183216335505, 4090.1819636072905, 4137.172565486914, 1270.6632742323293, 1283.6983308922395, 1286.3825363825417, 1297.8896793772446, 1299.4867027524076, 962.6390103711109, 987.6790043235977, 998.4458625462938, 1008.606643409071, 1021.4484178543652, 5890.263717035371, 6073.392660733891, 6098.261995331398, 6165.537584681167, 6446.29090800021, 873.3231745397368, 991.9553620087113, 992.2062350119875, 1028.711960651019, 1052.9315594486334, 3986.3223252047223, 4067.3085575452246, 4100.138970816102, 4166.687498437629, 4184.309588917856, 10855.728606784893, 11242.606508772154, 11245.606403775992, 11408.771824563462, 11411.714707132222, 11133.721656958642, 11233.494492747846, 11252.212345135762, 11252.437378131224, 11272.436378181059, 8321.251087402163, 8348.165183481724, 8577.01364343096, 8664.263537599249, 8675.6529738811, 5332.893342133213, 5837.182794408871, 5975.940240597713, 6054.576179667381, 6139.2325959255095, 7939.6030198491, 9399.671011514838, 9675.612975480903, 9829.950850245703, 9846.655367062405, 8300.750977470685, 9646.517674116125, 9864.21086313079, 10018.799624007333, 10187.43969081678, 7117.82205444868, 7281.308275713799, 7782.88325675104, 7991.2807847294025, 8215.09633940278, 8278.958605206935, 8628.611712472828, 8645.092265312303, 8661.350398719995, 8662.263707584925, 7449.217832127549, 7580.0, 7632.1604623492485, 7665.8466830661955, 7716.5755883426955, 7087.830507966128, 7290.234525374972, 7436.367908727602, 7568.091828980558, 7635.121960974559, 6443.355664433519, 7206.063211782434, 7252.093488313961, 7253.637318134178, 7254.818629534304, 5012.0, 5033.672811267256, 5134.998675258438, 5201.427842937198, 5234.555062819703, 4747.430308363018, 4957.776900039579, 4964.304997300309, 5165.741712914414, 5218.217267409919], "value": 4756.707039458141, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.38965135197185, 331.4547569248586, 334.0213175396089, 350.6563567703649, 354.6524406082038, 6796.694148763305, 6835.111435513382, 6851.588904665724, 6860.005299231609, 6869.0, 6094.7810437912485, 6732.697028633711, 6756.864862702743, 6777.2545020047755, 6784.694688739006, 4333.263345231318, 4850.781714822824, 4894.9965257122385, 4937.7777999990085, 4992.276119962603, 4883.1698611236, 4963.578095861856, 4972.378452693413, 4987.102321582114, 4991.301217829507, 4929.21132618781, 4929.580985616227, 4929.728864912428, 4949.282354058677, 4960.801567937297, 4707.882302942419, 4890.388701412324, 4899.1426500362295, 4899.681520701179, 4909.116359055369, 4177.4569305990335, 4266.679999000087, 4270.59429354214, 4282.635975942048, 4297.076128632328, 6375.4262116409745, 6404.039394090877, 6466.967665161691, 6471.255805582324, 6500.577462464925, 5938.376470470582, 5941.524678025791, 5954.46409823118, 5968.134620480049, 5973.551983601247, 6949.131358580177, 7238.384737297336, 7249.093863267091, 7257.6371181441245, 7270.127584689871, 4819.2530157825595, 4833.492483289274, 4853.4418541867435, 4863.805447782084, 4866.197077482211, 7492.925070749277, 7680.38556915451, 7693.653785579606, 7699.884501732478, 7710.614469276514, 4966.130927087817, 4982.252662100642, 4984.554479201063, 4996.550310472002, 4996.825111121093, 5058.3929928408825, 5063.417706963673, 5069.264956581275, 5070.087384270793, 5074.543291103819, 5745.482906538432, 5958.165856780054, 5959.672218027963, 5965.224520812353, 5974.103884417285, 7250.274972502813, 7297.963510182415, 7314.45141614381, 7328.303811137934, 7329.633518324063, 1722.0098443395098, 1753.1234382808595, 1754.929802807899, 1757.1214392803597, 1758.9560260993578, 848.4951453884929, 975.190591808797, 1099.6646022962914, 1109.755853712182, 1141.5490881101919, 6650.3682150195655, 6805.183377994574, 6807.149106361705, 6815.625140617214, 6886.8622627548175, 7339.559626422431, 7451.0, 7453.888191677129, 7632.847343053108, 7638.0, 6942.236354001056, 6965.442764578822, 6971.6862741176765, 6976.546524475994, 6980.371766541036, 3944.9211015779524, 4012.217617564547, 4015.638592526687, 4015.9799201003807, 4019.055521952339, 7245.347918687238, 7673.731419400294, 7958.2041795821115, 7960.641771120314, 7980.68077276914, 7811.0, 7856.960715196502, 7865.724699635485, 7871.960640196762, 7884.989327721509, 7967.402444816662, 7974.202579742096, 7980.760577182694, 8007.439479236514, 8010.679572817022, 7325.706971721072, 7599.240075992578, 7600.543967361976, 7608.733694320894, 7616.8476630465975, 7425.554466732014, 7481.4014878808475, 7482.663280152515, 7489.70041198346, 7503.062117235346, 6405.327440618855, 7914.24814642608, 7966.083900351534, 7969.840603187788, 7977.521348719096, 4971.428285747185, 4978.327925730125, 4982.900341993212, 4984.526469985346, 4997.475265097112, 7231.5299505531975, 7576.24237576238, 7578.280063393969, 7585.506942048749, 7596.316331530189, 6997.300269973166, 7011.754588589579, 7286.089238845145, 7339.119305683352, 7361.2638736125955, 7308.488405811542, 7312.780616581511, 7328.105398367721, 7330.120385553768, 7342.596157211405, 5798.391168927202, 7185.964070179615, 7195.46034047459, 7199.1361036675935, 7201.3518783309755, 6169.598976066542, 7045.859082818418, 7284.745033923788, 7309.451791115794, 7314.268573142643, 6820.658967051727, 6961.686724097326, 6996.425804194016, 7162.283771622796, 7186.245444228281], "value": 5338.126833991591, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.8140067279479, 686.2451303566078, 693.3136195166783, 770.1412924589085, 773.5861314196906, 15363.385464581403, 15548.45580404686, 15618.843811561881, 15647.982881112734, 15652.92173539132, 14203.289835508234, 14266.215358155298, 14295.428182872673, 14298.57104286873, 14299.0, 776.5224387001981, 948.4451595816456, 978.1441238915949, 978.1538968792015, 982.5185659027061, 958.0515289863021, 969.6024629901742, 970.6360114956891, 971.2909576009517, 974.8537719342085, 827.664795757719, 1024.8718910136233, 1026.8151732688113, 1027.085893554737, 1029.2229366828035, 1000.5647543318644, 1030.2685093583564, 1030.3920686794827, 1031.1802117316752, 1033.607229252879, 775.1086250811555, 775.6897241103547, 777.3470284960661, 777.3858651665178, 777.4713195027379, 13633.795493067611, 14010.0, 15118.773218401733, 15212.163331016789, 15224.771628425582, 12704.428300726397, 13048.217106973612, 13189.802152967713, 13309.334533273297, 13546.187228766306, 9149.451032938045, 10063.5471403787, 10167.542460589291, 10282.485875706185, 10515.369077855354, 3547.609762926076, 3569.5359603251673, 3711.6659500645073, 3763.849446022156, 3844.0966372902626, 88332.55833720854, 88804.0, 88956.0, 89076.6638500423, 89262.0, 8583.570821458901, 9051.502167380857, 9485.241180705527, 9501.524923753785, 10394.584216631401, 21387.46531336729, 21570.35288941334, 21631.134754609953, 21636.675449868264, 21695.0, 7520.871869219552, 7562.546247225184, 7562.886556701654, 7803.102643195992, 7844.921550784533, 4147.398627199039, 4218.894527636834, 4231.217224813419, 4404.7357158570585, 4481.35020422037, 3547.7161827053774, 3682.668559829628, 3687.9631203688155, 3733.775973441602, 3740.2706472238197, 650.7657243392375, 656.3370995294787, 659.475716805141, 671.4225765841372, 674.2145400608237, 561.3600495435207, 571.6055921414211, 594.0791772752303, 616.0, 627.3193584960369, 7831.138574756772, 8526.658933642584, 8831.514266715263, 8868.246199073152, 8894.777630559158, 606.9484093852072, 621.996890015547, 624.0701354981136, 634.1565717595587, 635.0505993539688, 3765.962340376616, 4003.099302656937, 4036.1120553478186, 4081.653059489977, 4106.07613287008, 89059.66410503848, 91854.54072729727, 91861.54069229745, 92128.0, 92237.0, 74409.0, 75086.62456687681, 76517.0, 78390.60804696054, 81485.0, 63134.68432657807, 63789.681051594445, 65099.674501628135, 71953.2804671957, 73348.63325683337, 8421.789455263668, 8430.325573954191, 8593.0, 8727.170918762708, 8734.213920747163, 47039.0, 47701.76149119232, 48235.79410514765, 50628.987420250654, 51186.74406627943, 51761.636360015495, 62685.0, 67698.6460270782, 69580.0, 69720.65139674269, 10768.138548916224, 11168.162387820783, 11175.441227938409, 11331.830022549504, 11432.371219583003, 11854.348010859485, 11960.342181180118, 12137.332446715516, 12161.939190303992, 12521.812172817234, 11422.200445969034, 11517.654470365902, 11524.0, 11689.649310520697, 11712.88287117118, 11199.832002519806, 11565.884341156818, 11598.304101753922, 11604.825927611262, 12100.213486123374, 11221.326720396803, 11257.099432045581, 11367.976882080653, 11565.884341156481, 11624.941875290908, 7704.537727736129, 9385.343025988115, 9467.053294670477, 9580.95209523948, 9706.077922597342, 9191.415348014147, 9331.953340233254, 9361.906380936376, 9374.156325930699, 9409.341346105706], "value": 7047.425353664794, "unit": "score"}], "name": "dromaeo_css", "value": 7633.197992261628, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 118.0, 112.0, 111.0, 111.0, 112.0, 110.0, 111.0, 133.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 120.0, 143.0, 166.0, 119.0, 140.0, 118.0, 151.0, 161.0, 146.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 186.0, 185.0, 183.0, 183.0, 183.0, 183.0, 181.0, 183.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 78.0, 156.0, 56.0, 55.0, 55.0, 61.0, 132.0, 56.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 140.0, 79.0, 79.0, 90.0, 128.0, 80.0, 77.0, 127.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 86.0, 85.0, 88.0, 85.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 92.0, 93.0, 93.0, 92.0, 106.0, 149.0, 117.0, 92.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 84.0, 82.0, 83.0, 86.0, 84.0, 83.0, 110.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 65.0, 64.0, 64.0, 63.0, 65.0, 81.0, 58.0, 55.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 67.0, 67.0, 74.0, 75.0, 68.0, 67.0, 72.0, 65.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 79.0, 79.0, 77.0, 81.0, 77.0, 78.0, 81.0, 83.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 146.0, 112.0, 148.0, 146.0, 132.0, 124.0, 126.0, 143.0, 139.0], "value": 135.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 163.0, 162.0, 157.0, 156.0, 162.0, 165.0, 158.0, 158.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 66.0, 68.0, 67.0, 65.0, 65.0, 66.0, 67.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1466.2, "alertThreshold": 2.0}]} 18:18:25 INFO - Return code: 0 18:18:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:18:25 ERROR - # TBPL SUCCESS # 18:18:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:18:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:18:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:18:25 INFO - 2016-07-10 18:18:25.453 screenresolution[1676:10191] starting screenresolution argv=screenresolution get 18:18:25 INFO - 2016-07-10 18:18:25.456 screenresolution[1676:10191] Display 0: 1600x1200x32@60 18:18:25 INFO - 2016-07-10 18:18:25.462 screenresolution[1677:10194] starting screenresolution argv=screenresolution list 18:18:25 INFO - Available Modes on Display 0 18:18:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:18:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:18:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:18:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:18:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:18:25 INFO - Intel Iris: 18:18:25 INFO - Chipset Model: Intel Iris 18:18:25 INFO - Type: GPU 18:18:25 INFO - Bus: Built-In 18:18:25 INFO - VRAM (Dynamic, Max): 1536 MB 18:18:25 INFO - Vendor: Intel (0x8086) 18:18:25 INFO - Device ID: 0x0a2e 18:18:25 INFO - Revision ID: 0x0009 18:18:25 INFO - Displays: 18:18:25 INFO - Display: 18:18:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:18:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:18:25 INFO - Display Serial Number: 1600x1200 60 18:18:25 INFO - Main Display: Yes 18:18:25 INFO - Mirror: Off 18:18:25 INFO - Online: Yes 18:18:25 INFO - Rotation: Supported 18:18:25 INFO - Return code: 0 18:18:25 INFO - Running post-action listener: _resource_record_post_action 18:18:25 INFO - Running post-run listener: _resource_record_post_run 18:18:26 INFO - Total resource usage - Wall time: 1195s; CPU: 53.0%; Read bytes: 37660160; Write bytes: 494055424; Read time: 393; Write time: 1929 18:18:26 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 196655616; Write bytes: 188323840; Read time: 17638; Write time: 720 18:18:26 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 30551552; Write bytes: 294942720; Read time: 306; Write time: 1180 18:18:26 INFO - Running post-run listener: _upload_blobber_files 18:18:26 INFO - Blob upload gear active. 18:18:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:18:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:18:27 INFO - (blobuploader) - INFO - Open directory for files ... 18:18:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:18:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:18:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:28 INFO - (blobuploader) - INFO - Done attempting. 18:18:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:18:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:18:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:29 INFO - (blobuploader) - INFO - Done attempting. 18:18:29 INFO - (blobuploader) - INFO - Iteration through files over. 18:18:29 INFO - Return code: 0 18:18:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:18:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:18:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65d721298223f73b7be21dca1e23d0b2033871a48c3877986e48043dff1d62f319b83a3ad240c1394ee0fde0edabaec713d63be457d2b53023668115cd67c61c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/676bbd4e0dd864e19f1574fda6061a5481770dca91853023e2b64ef3150948a453698979306aae51b528295fe943af4c666833991c7d837e3f29cf4b4df8902f"} 18:18:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:18:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:18:29 INFO - Contents: 18:18:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65d721298223f73b7be21dca1e23d0b2033871a48c3877986e48043dff1d62f319b83a3ad240c1394ee0fde0edabaec713d63be457d2b53023668115cd67c61c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/676bbd4e0dd864e19f1574fda6061a5481770dca91853023e2b64ef3150948a453698979306aae51b528295fe943af4c666833991c7d837e3f29cf4b4df8902f"} 18:18:29 INFO - Running post-run listener: copy_logs_to_upload_dir 18:18:29 INFO - Copying logs to upload dir... 18:18:29 INFO - mkdir: /builds/slave/test/build/upload/logs 18:18:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.102346 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-07-10 18:18:29.338503) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 18:18:29.342770) ========= 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.kI9pCHTT93/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.i0unTEexnC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65d721298223f73b7be21dca1e23d0b2033871a48c3877986e48043dff1d62f319b83a3ad240c1394ee0fde0edabaec713d63be457d2b53023668115cd67c61c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/676bbd4e0dd864e19f1574fda6061a5481770dca91853023e2b64ef3150948a453698979306aae51b528295fe943af4c666833991c7d837e3f29cf4b4df8902f"} build_url:https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011087 build_url: 'https://queue.taskcluster.net/v1/task/QHiPSgAPQA2obcrozWyfpQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/65d721298223f73b7be21dca1e23d0b2033871a48c3877986e48043dff1d62f319b83a3ad240c1394ee0fde0edabaec713d63be457d2b53023668115cd67c61c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/676bbd4e0dd864e19f1574fda6061a5481770dca91853023e2b64ef3150948a453698979306aae51b528295fe943af4c666833991c7d837e3f29cf4b4df8902f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 18:18:29.380901) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 18:18:29.381530) ========= 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.kI9pCHTT93/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.i0unTEexnC/Listeners TMPDIR=/var/folders/sw/56y5nypd5nvd19g5t_5x1crw00000w/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.004752 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 18:18:29.431223) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 18:18:29.431726) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 18:18:29.432090) ========= ========= Total master_lag: 0.19 =========