builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0068 starttime: 1461323294.64 results: success (0) buildid: 20160422014259 builduid: 56a85fe4813441aca3e137e3f2dba16f revision: b813b1e6e7e3288a865965bb9beefbf9d36b4a67 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:14.644656) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:14.645094) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:14.645368) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:14.668227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:14.668505) ========= 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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-04-22 04:08:14-- 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% 36.6M=0s 2016-04-22 04:08:14 (36.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123224 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:14.808373) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:14.808735) ========= 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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.020793 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:14.860990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 04:08:14.861390) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b813b1e6e7e3288a865965bb9beefbf9d36b4a67 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b813b1e6e7e3288a865965bb9beefbf9d36b4a67 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-04-22 04:08:14,924 truncating revision to first 12 chars 2016-04-22 04:08:14,924 Setting DEBUG logging. 2016-04-22 04:08:14,924 attempt 1/10 2016-04-22 04:08:14,924 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b813b1e6e7e3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 04:08:16,446 unpacking tar archive at: fx-team-b813b1e6e7e3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.768473 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 04:08:16.649759) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:16.650090) ========= script_repo_revision: b813b1e6e7e3288a865965bb9beefbf9d36b4a67 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:16.650487) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:16.650762) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 04:08:16.666701) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-04-22 04:08:16.667020) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:08:16 INFO - MultiFileLogger online at 20160422 04:08:16 in /builds/slave/test 04:08:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:08:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:08:16 INFO - {'append_to_log': False, 04:08:16 INFO - 'base_work_dir': '/builds/slave/test', 04:08:16 INFO - 'blob_upload_branch': 'Fx-Team', 04:08:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:08:16 INFO - 'branch': 'Fx-Team', 04:08:16 INFO - 'buildbot_json_path': 'buildprops.json', 04:08:16 INFO - 'config_files': ('talos/mac_config.py',), 04:08:16 INFO - 'default_actions': ('clobber', 04:08:16 INFO - 'read-buildbot-config', 04:08:16 INFO - 'download-and-extract', 04:08:16 INFO - 'populate-webroot', 04:08:16 INFO - 'create-virtualenv', 04:08:16 INFO - 'install', 04:08:16 INFO - 'run-tests'), 04:08:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:08:16 INFO - 'download_minidump_stackwalk': True, 04:08:16 INFO - 'download_symbols': 'ondemand', 04:08:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:08:16 INFO - 'tooltool.py': '/tools/tooltool.py', 04:08:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:08:16 INFO - '/tools/misc-python/virtualenv.py')}, 04:08:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:08:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:08:16 INFO - 'installer_path': 'installer.exe', 04:08:16 INFO - 'log_level': 'info', 04:08:16 INFO - 'log_name': 'talos', 04:08:16 INFO - 'log_to_console': True, 04:08:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:08:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:08:16 INFO - 'opt_config_files': (), 04:08:16 INFO - 'pip_index': False, 04:08:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:08:16 INFO - 'cmd': ('bash', 04:08:16 INFO - '-c', 04:08:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:08:16 INFO - 'enabled': True, 04:08:16 INFO - 'halt_on_failure': False, 04:08:16 INFO - 'name': 'check_screen_resolution'},), 04:08:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:08:16 INFO - 'cmd': ('bash', 04:08:16 INFO - '-c', 04:08:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:08:16 INFO - 'enabled': True, 04:08:16 INFO - 'halt_on_failure': False, 04:08:16 INFO - 'name': 'check_screen_resolution'},), 04:08:16 INFO - 'run_cmd_checks_enabled': True, 04:08:16 INFO - 'sps_profile': False, 04:08:16 INFO - 'sps_profile_interval': 0, 04:08:16 INFO - 'suite': 'dromaeojs', 04:08:16 INFO - 'system_bits': '32', 04:08:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:08:16 INFO - 'title': 't-yosemite-r7-0068', 04:08:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:08:16 INFO - 'use_talos_json': True, 04:08:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:08:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:08:16 INFO - 'work_dir': 'build'} 04:08:16 INFO - ##### 04:08:16 INFO - ##### Running clobber step. 04:08:16 INFO - ##### 04:08:16 INFO - Running pre-action listener: _resource_record_pre_action 04:08:16 INFO - Running main action method: clobber 04:08:16 INFO - rmtree: /builds/slave/test/build 04:08:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:08:18 INFO - Running post-action listener: _resource_record_post_action 04:08:18 INFO - ##### 04:08:18 INFO - ##### Running read-buildbot-config step. 04:08:18 INFO - ##### 04:08:18 INFO - Running pre-action listener: _resource_record_pre_action 04:08:18 INFO - Running main action method: read_buildbot_config 04:08:18 INFO - Using buildbot properties: 04:08:18 INFO - { 04:08:18 INFO - "project": "", 04:08:18 INFO - "product": "firefox", 04:08:18 INFO - "script_repo_revision": "production", 04:08:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:08:18 INFO - "repository": "", 04:08:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:08:18 INFO - "buildid": "20160422014259", 04:08:18 INFO - "pgo_build": "False", 04:08:18 INFO - "basedir": "/builds/slave/test", 04:08:18 INFO - "buildnumber": 752, 04:08:18 INFO - "slavename": "t-yosemite-r7-0068", 04:08:18 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:08:18 INFO - "platform": "yosemite_r7", 04:08:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:08:18 INFO - "branch": "fx-team", 04:08:18 INFO - "repo_path": "integration/fx-team", 04:08:18 INFO - "revision": "b813b1e6e7e3288a865965bb9beefbf9d36b4a67", 04:08:18 INFO - "stage_platform": "macosx64", 04:08:18 INFO - "builduid": "56a85fe4813441aca3e137e3f2dba16f", 04:08:18 INFO - "slavebuilddir": "test" 04:08:18 INFO - } 04:08:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:08:18 INFO - Running post-action listener: _resource_record_post_action 04:08:18 INFO - ##### 04:08:18 INFO - ##### Running download-and-extract step. 04:08:18 INFO - ##### 04:08:18 INFO - Running pre-action listener: _resource_record_pre_action 04:08:18 INFO - Running main action method: download_and_extract 04:08:18 INFO - mkdir: /builds/slave/test/build/tests 04:08:18 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')]} 04:08:18 INFO - https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:08:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:08:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:08:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:08:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 04:08:18 INFO - Downloaded 1199 bytes. 04:08:18 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:08:18 INFO - Using the following test package requirements: 04:08:18 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:08:18 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:08:18 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:08:18 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:08:18 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:08:18 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:08:18 INFO - u'jsshell-mac.zip'], 04:08:18 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:08:18 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:08:18 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:08:18 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:08:18 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:08:18 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:08:18 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:08:18 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:08:18 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:08:18 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:08:18 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:08:18 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:08:18 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')]} 04:08:18 INFO - https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:08:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:08:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:08:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:08:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:08:20 INFO - Downloaded 18105772 bytes. 04:08:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:08:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:08:23 INFO - Return code: 0 04:08:23 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:08:23 INFO - https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:08:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:08:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:08:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:08:24 INFO - Downloaded 18105772 bytes. 04:08:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:08:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:08:28 INFO - Return code: 0 04:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:08:28 INFO - https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:08:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:08:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:08:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:08:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:08:29 INFO - Downloaded 10845952 bytes. 04:08:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:08:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:08:29 INFO - Return code: 0 04:08: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')]} 04:08:29 INFO - https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:08:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:08:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:08:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:08:32 INFO - Downloaded 110027955 bytes. 04:08:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:08:32 INFO - mkdir: /builds/slave/test/properties 04:08:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:08:32 INFO - Writing to file /builds/slave/test/properties/build_url 04:08:32 INFO - Contents: 04:08:32 INFO - build_url:https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:08:33 INFO - Running post-action listener: _resource_record_post_action 04:08:33 INFO - Running post-action listener: set_extra_try_arguments 04:08:33 INFO - ##### 04:08:33 INFO - ##### Running populate-webroot step. 04:08:33 INFO - ##### 04:08:33 INFO - Running pre-action listener: _resource_record_pre_action 04:08:33 INFO - Running main action method: populate_webroot 04:08:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:08:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:08:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:08:33 INFO - '--tppagecycles', 04:08:33 INFO - '7'], 04:08:33 INFO - 'tests': ['tsvgm']}}, 04:08:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:08:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:08:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:08:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:08:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:33 INFO - 'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:08:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:33 INFO - 'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['damp', 'tps']}, 04:08:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:33 INFO - 'tests': ['damp', 'tps']}, 04:08:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['dromaeo_dom']}, 04:08:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:08:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['a11yr', 04:08:33 INFO - 'ts_paint', 04:08:33 INFO - 'tpaint', 04:08:33 INFO - 'sessionrestore', 04:08:33 INFO - 'sessionrestore_no_auto_restore', 04:08:33 INFO - 'tabpaint']}, 04:08:33 INFO - 'other-e10s': {'tests': ['a11yr', 04:08:33 INFO - 'ts_paint', 04:08:33 INFO - 'tpaint', 04:08:33 INFO - 'sessionrestore', 04:08:33 INFO - 'sessionrestore_no_auto_restore', 04:08:33 INFO - 'tabpaint']}, 04:08:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:08:33 INFO - 'ts_paint', 04:08:33 INFO - 'tpaint', 04:08:33 INFO - 'sessionrestore', 04:08:33 INFO - 'sessionrestore_no_auto_restore', 04:08:33 INFO - 'tabpaint']}, 04:08:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:08:33 INFO - 'ts_paint', 04:08:33 INFO - 'tpaint', 04:08:33 INFO - 'sessionrestore', 04:08:33 INFO - 'sessionrestore_no_auto_restore', 04:08:33 INFO - 'tabpaint']}, 04:08:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['a11yr', 04:08:33 INFO - 'ts_paint', 04:08:33 INFO - 'tpaint', 04:08:33 INFO - 'sessionrestore', 04:08:33 INFO - 'sessionrestore_no_auto_restore', 04:08:33 INFO - 'tabpaint']}, 04:08:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['a11yr', 04:08:33 INFO - 'ts_paint', 04:08:33 INFO - 'tpaint', 04:08:33 INFO - 'sessionrestore', 04:08:33 INFO - 'sessionrestore_no_auto_restore', 04:08:33 INFO - 'tabpaint']}, 04:08:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['tsvgx', 04:08:33 INFO - 'tsvgr_opacity', 04:08:33 INFO - 'tart', 04:08:33 INFO - 'tscrollx', 04:08:33 INFO - 'cart']}, 04:08:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:08:33 INFO - 'tsvgr_opacity', 04:08:33 INFO - 'tart', 04:08:33 INFO - 'tscrollx', 04:08:33 INFO - 'cart']}, 04:08:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:33 INFO - 'talos_options': ['--disable-e10s'], 04:08:33 INFO - 'tests': ['tp5o']}, 04:08:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:33 INFO - 'tests': ['tp5o']}, 04:08:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:33 INFO - 'talos_options': ['--disable-e10s', 04:08:33 INFO - '--xperf_path', 04:08:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:33 INFO - 'tests': ['tp5n']}, 04:08:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:08:33 INFO - 'talos_options': ['--xperf_path', 04:08:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:08:33 INFO - 'tests': ['tp5n']}}, 04:08:33 INFO - 'talos.zip': {'path': '', 04:08:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:08:33 INFO - Running post-action listener: _resource_record_post_action 04:08:33 INFO - ##### 04:08:33 INFO - ##### Running create-virtualenv step. 04:08:33 INFO - ##### 04:08:33 INFO - Running pre-action listener: _resource_record_pre_action 04:08:33 INFO - Running main action method: create_virtualenv 04:08:33 INFO - Creating virtualenv /builds/slave/test/build/venv 04:08:33 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 04:08:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:08:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:08:33 INFO - Using real prefix '/tools/python27' 04:08:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:08:34 INFO - Installing distribute.............................................................................................................................................................................................done. 04:08:37 INFO - Installing pip.................done. 04:08:37 INFO - Return code: 0 04:08:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:08:37 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')]} 04:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:37 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')]} 04:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:37 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 0x10d09aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5d1da6060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, '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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:08:37 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 04:08:37 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 04:08:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:08:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:37 INFO - 'HOME': '/Users/cltbld', 04:08:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:37 INFO - 'LOGNAME': 'cltbld', 04:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:37 INFO - 'MOZ_NO_REMOTE': '1', 04:08:37 INFO - 'NO_EM_RESTART': '1', 04:08:37 INFO - 'PAGER': '/bin/cat', 04:08:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:37 INFO - 'PWD': '/builds/slave/test', 04:08:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:37 INFO - 'SHELL': '/bin/bash', 04:08:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:08:37 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:08:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:37 INFO - 'USER': 'cltbld', 04:08:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:37 INFO - 'XPC_FLAGS': '0x0', 04:08:37 INFO - 'XPC_SERVICE_NAME': '0', 04:08:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:37 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 04:08:37 INFO - Cleaning up... 04:08:37 INFO - Return code: 0 04:08:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:08:37 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')]} 04:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:37 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')]} 04:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:37 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 0x10d09aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5d1da6060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, '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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:08:37 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 04:08:37 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 04:08:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:08:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:37 INFO - 'HOME': '/Users/cltbld', 04:08:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:37 INFO - 'LOGNAME': 'cltbld', 04:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:37 INFO - 'MOZ_NO_REMOTE': '1', 04:08:37 INFO - 'NO_EM_RESTART': '1', 04:08:37 INFO - 'PAGER': '/bin/cat', 04:08:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:37 INFO - 'PWD': '/builds/slave/test', 04:08:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:37 INFO - 'SHELL': '/bin/bash', 04:08:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:08:37 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:08:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:37 INFO - 'USER': 'cltbld', 04:08:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:37 INFO - 'XPC_FLAGS': '0x0', 04:08:37 INFO - 'XPC_SERVICE_NAME': '0', 04:08:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:37 INFO - Downloading/unpacking psutil>=0.7.1 04:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:08:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:40 INFO - Installing collected packages: psutil 04:08:40 INFO - Running setup.py install for psutil 04:08:40 INFO - building 'psutil._psutil_osx' extension 04:08:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:08:41 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 04:08:41 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 04:08:41 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 04:08:41 INFO - building 'psutil._psutil_posix' extension 04:08:41 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 04:08:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:08:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:08:41 INFO - ^ 04:08:41 INFO - 1 warning generated. 04:08:41 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 04:08:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:08:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:08:41 INFO - Successfully installed psutil 04:08:41 INFO - Cleaning up... 04:08:41 INFO - Return code: 0 04:08:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:08:41 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')]} 04:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:41 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')]} 04:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:41 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 0x10d09aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5d1da6060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, '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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:08:41 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 04:08:41 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 04:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:41 INFO - 'HOME': '/Users/cltbld', 04:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:41 INFO - 'LOGNAME': 'cltbld', 04:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:41 INFO - 'MOZ_NO_REMOTE': '1', 04:08:41 INFO - 'NO_EM_RESTART': '1', 04:08:41 INFO - 'PAGER': '/bin/cat', 04:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:41 INFO - 'PWD': '/builds/slave/test', 04:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:41 INFO - 'SHELL': '/bin/bash', 04:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:08:41 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:41 INFO - 'USER': 'cltbld', 04:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:41 INFO - 'XPC_FLAGS': '0x0', 04:08:41 INFO - 'XPC_SERVICE_NAME': '0', 04:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:08:41 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 04:08:41 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 04:08:41 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 04:08:41 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 04:08:41 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 04:08:41 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 04:08:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:08:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:08:44 INFO - Installing collected packages: mozsystemmonitor 04:08:44 INFO - Running setup.py install for mozsystemmonitor 04:08:44 INFO - Successfully installed mozsystemmonitor 04:08:44 INFO - Cleaning up... 04:08:44 INFO - Return code: 0 04:08:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:08:44 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')]} 04:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:44 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')]} 04:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:44 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 0x10d09aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5d1da6060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, '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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:08:44 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 04:08:44 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 04:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:44 INFO - 'HOME': '/Users/cltbld', 04:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:44 INFO - 'LOGNAME': 'cltbld', 04:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:44 INFO - 'MOZ_NO_REMOTE': '1', 04:08:44 INFO - 'NO_EM_RESTART': '1', 04:08:44 INFO - 'PAGER': '/bin/cat', 04:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:44 INFO - 'PWD': '/builds/slave/test', 04:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:44 INFO - 'SHELL': '/bin/bash', 04:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:08:44 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:44 INFO - 'USER': 'cltbld', 04:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:44 INFO - 'XPC_FLAGS': '0x0', 04:08:44 INFO - 'XPC_SERVICE_NAME': '0', 04:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:44 INFO - Downloading/unpacking blobuploader==1.2.4 04:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:47 INFO - Downloading blobuploader-1.2.4.tar.gz 04:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:08:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:08:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:08:48 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 04:08:48 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 04:08:48 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 04:08:48 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 04:08:48 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 04:08:48 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 04:08:48 INFO - Downloading docopt-0.6.1.tar.gz 04:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:08:48 INFO - Installing collected packages: blobuploader, requests, docopt 04:08:48 INFO - Running setup.py install for blobuploader 04:08:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:08:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:08:48 INFO - Running setup.py install for requests 04:08:49 INFO - Running setup.py install for docopt 04:08:49 INFO - Successfully installed blobuploader requests docopt 04:08:49 INFO - Cleaning up... 04:08:49 INFO - Return code: 0 04:08:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:49 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')]} 04:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:49 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')]} 04:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:49 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 0x10d09aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5d1da6060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, '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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:08:49 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 04:08:49 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 04:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:49 INFO - 'HOME': '/Users/cltbld', 04:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:49 INFO - 'LOGNAME': 'cltbld', 04:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:49 INFO - 'MOZ_NO_REMOTE': '1', 04:08:49 INFO - 'NO_EM_RESTART': '1', 04:08:49 INFO - 'PAGER': '/bin/cat', 04:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:49 INFO - 'PWD': '/builds/slave/test', 04:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:49 INFO - 'SHELL': '/bin/bash', 04:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:08:49 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:49 INFO - 'USER': 'cltbld', 04:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:49 INFO - 'XPC_FLAGS': '0x0', 04:08:49 INFO - 'XPC_SERVICE_NAME': '0', 04:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:08:49 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-vZcF7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:08:49 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-F4fSfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:08:49 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-dTS3nB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-bOWcjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-TxU0dG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-N2OeiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-yBrJIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-yR0dLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-8BoCIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-OGRqnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-6PsDV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-2Xrejj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:08:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-6IpZoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:08:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-YSepxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-AcTVjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:08:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-DnGDBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:08:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-eecgm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:08:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:08:51 INFO - Running setup.py install for manifestparser 04:08:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:08:51 INFO - Running setup.py install for mozcrash 04:08:51 INFO - Running setup.py install for mozdebug 04:08:51 INFO - Running setup.py install for mozdevice 04:08:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:08:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:08:52 INFO - Running setup.py install for mozfile 04:08:52 INFO - Running setup.py install for mozhttpd 04:08:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:08:52 INFO - Running setup.py install for mozinfo 04:08:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:08:52 INFO - Running setup.py install for mozInstall 04:08:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:08:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:08:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:08:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:08:52 INFO - Running setup.py install for mozleak 04:08:52 INFO - Running setup.py install for mozlog 04:08:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:08:52 INFO - Running setup.py install for moznetwork 04:08:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:08:53 INFO - Running setup.py install for mozprocess 04:08:53 INFO - Running setup.py install for mozprofile 04:08:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:08:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:08:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:08:53 INFO - Running setup.py install for mozrunner 04:08:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:08:53 INFO - Running setup.py install for mozscreenshot 04:08:53 INFO - Running setup.py install for moztest 04:08:53 INFO - Running setup.py install for mozversion 04:08:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:08:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:08:53 INFO - Cleaning up... 04:08:54 INFO - Return code: 0 04:08:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08:54 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')]} 04:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:54 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')]} 04:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:54 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 0x10d09aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5d1da6060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, '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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:08:54 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 04:08:54 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 04:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:54 INFO - 'HOME': '/Users/cltbld', 04:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:54 INFO - 'LOGNAME': 'cltbld', 04:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:54 INFO - 'MOZ_NO_REMOTE': '1', 04:08:54 INFO - 'NO_EM_RESTART': '1', 04:08:54 INFO - 'PAGER': '/bin/cat', 04:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:54 INFO - 'PWD': '/builds/slave/test', 04:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:54 INFO - 'SHELL': '/bin/bash', 04:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:08:54 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:54 INFO - 'USER': 'cltbld', 04:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:54 INFO - 'XPC_FLAGS': '0x0', 04:08:54 INFO - 'XPC_SERVICE_NAME': '0', 04:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:08:54 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-UEpXPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:08:54 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)) 04:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:08:54 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-h3QyyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:08:54 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)) 04:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:08:54 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-dhBB5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:08:54 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)) 04:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:08:54 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-IUhedM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:08:54 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)) 04:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:08:54 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-OKXf6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:08:54 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)) 04:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:08:54 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-VtwGzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:08:54 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)) 04:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:08:54 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-FB6nF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Sl4dwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-qffgH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-HGnmVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-BwpS2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-8FgjW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-lzPobK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-1ExIQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-3UBrDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-fsKJZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:08:55 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)) 04:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:08:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-UBes4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:08:56 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)) 04:08:56 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)) 04:08:56 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)) 04:08:56 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)) 04:08:56 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)) 04:08:56 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)) 04:08:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:08:56 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 04:08:56 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 04:08:56 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 04:08:56 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 04:08:56 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 04:08:56 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 04:08:58 INFO - Downloading blessings-1.6.tar.gz 04:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:08:58 INFO - Installing collected packages: blessings 04:08:58 INFO - Running setup.py install for blessings 04:08:58 INFO - Successfully installed blessings 04:08:58 INFO - Cleaning up... 04:08:58 INFO - Return code: 0 04:08:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:08:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:08:59 INFO - Reading from file tmpfile_stdout 04:08:59 INFO - Current package versions: 04:08:59 INFO - blessings == 1.6 04:08:59 INFO - blobuploader == 1.2.4 04:08:59 INFO - docopt == 0.6.1 04:08:59 INFO - manifestparser == 1.1 04:08:59 INFO - mozInstall == 1.12 04:08:59 INFO - mozcrash == 0.17 04:08:59 INFO - mozdebug == 0.1 04:08:59 INFO - mozdevice == 0.48 04:08:59 INFO - mozfile == 1.2 04:08:59 INFO - mozhttpd == 0.7 04:08:59 INFO - mozinfo == 0.9 04:08:59 INFO - mozleak == 0.1 04:08:59 INFO - mozlog == 3.1 04:08:59 INFO - moznetwork == 0.27 04:08:59 INFO - mozprocess == 0.22 04:08:59 INFO - mozprofile == 0.28 04:08:59 INFO - mozrunner == 6.11 04:08:59 INFO - mozscreenshot == 0.1 04:08:59 INFO - mozsystemmonitor == 0.0 04:08:59 INFO - moztest == 0.7 04:08:59 INFO - mozversion == 1.4 04:08:59 INFO - psutil == 3.1.1 04:08:59 INFO - requests == 1.2.3 04:08:59 INFO - wsgiref == 0.1.2 04:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:08: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')]} 04:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08: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')]} 04:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08:59 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 0x10d09aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5d1da6060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, '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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:08:59 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 04:08:59 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 04:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:59 INFO - 'HOME': '/Users/cltbld', 04:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:59 INFO - 'LOGNAME': 'cltbld', 04:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:59 INFO - 'MOZ_NO_REMOTE': '1', 04:08:59 INFO - 'NO_EM_RESTART': '1', 04:08:59 INFO - 'PAGER': '/bin/cat', 04:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:59 INFO - 'PWD': '/builds/slave/test', 04:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:59 INFO - 'SHELL': '/bin/bash', 04:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:08:59 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:59 INFO - 'USER': 'cltbld', 04:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:59 INFO - 'XPC_FLAGS': '0x0', 04:08:59 INFO - 'XPC_SERVICE_NAME': '0', 04:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:59 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)) 04:08:59 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)) 04:08:59 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)) 04:08:59 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)) 04:08:59 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)) 04:08:59 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)) 04:08:59 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)) 04:08:59 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)) 04:08:59 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)) 04:08:59 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)) 04:08:59 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)) 04:08:59 INFO - Cleaning up... 04:08:59 INFO - Return code: 0 04:08:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:08: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')]} 04:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08: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')]} 04:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:08: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d09aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5d1da6060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, '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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:08: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', 'jsonschema'] in /builds/slave/test/build 04:08: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 jsonschema 04:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:08:59 INFO - 'HOME': '/Users/cltbld', 04:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:08:59 INFO - 'LOGNAME': 'cltbld', 04:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:08:59 INFO - 'MOZ_NO_REMOTE': '1', 04:08:59 INFO - 'NO_EM_RESTART': '1', 04:08:59 INFO - 'PAGER': '/bin/cat', 04:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:08:59 INFO - 'PWD': '/builds/slave/test', 04:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:08:59 INFO - 'SHELL': '/bin/bash', 04:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:08:59 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:08:59 INFO - 'USER': 'cltbld', 04:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:08:59 INFO - 'XPC_FLAGS': '0x0', 04:08:59 INFO - 'XPC_SERVICE_NAME': '0', 04:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:08:59 INFO - Downloading/unpacking jsonschema 04:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:08:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:09:01 INFO - Downloading/unpacking functools32 (from jsonschema) 04:09:01 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 04:09:01 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 04:09:01 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 04:09:01 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 04:09:01 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 04:09:01 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 04:09:02 INFO - Downloading functools32-3.2.3-2.tar.gz 04:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:09:02 INFO - warning: no files found matching '*.txt' 04:09:02 INFO - no previously-included directories found matching 'build' 04:09:02 INFO - no previously-included directories found matching 'dist' 04:09:02 INFO - no previously-included directories found matching '.git*' 04:09:02 INFO - Installing collected packages: jsonschema, functools32 04:09:02 INFO - Running setup.py install for functools32 04:09:02 INFO - warning: no files found matching '*.txt' 04:09:02 INFO - no previously-included directories found matching 'build' 04:09:02 INFO - no previously-included directories found matching 'dist' 04:09:02 INFO - no previously-included directories found matching '.git*' 04:09:02 INFO - Successfully installed jsonschema functools32 04:09:02 INFO - Cleaning up... 04:09:02 INFO - Return code: 0 04:09:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:09:02 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 0x10d09aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d0aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5d1da6060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, '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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 04:09:02 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 04:09:02 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 04:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:09:02 INFO - 'HOME': '/Users/cltbld', 04:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:09:02 INFO - 'LOGNAME': 'cltbld', 04:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:02 INFO - 'MOZ_NO_REMOTE': '1', 04:09:02 INFO - 'NO_EM_RESTART': '1', 04:09:02 INFO - 'PAGER': '/bin/cat', 04:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:02 INFO - 'PWD': '/builds/slave/test', 04:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:09:02 INFO - 'SHELL': '/bin/bash', 04:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:09:02 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:09:02 INFO - 'USER': 'cltbld', 04:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:02 INFO - 'XPC_FLAGS': '0x0', 04:09:02 INFO - 'XPC_SERVICE_NAME': '0', 04:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:02 INFO - Downloading/unpacking flake8 04:09:02 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 04:09:02 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 04:09:02 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 04:09:02 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 04:09:02 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 04:09:02 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 04:09:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:09:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:09:05 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 04:09:05 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 04:09:05 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 04:09:05 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 04:09:05 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 04:09:05 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 04:09:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:09:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:09:05 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 04:09:05 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 04:09:05 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 04:09:05 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 04:09:05 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 04:09:05 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 04:09:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:09:06 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 04:09:06 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 04:09:06 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 04:09:06 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 04:09:06 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 04:09:06 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 04:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:09:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:09:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:09:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:09:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:09:06 INFO - no previously-included directories found matching 'docs/_build' 04:09:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:09:06 INFO - Running setup.py install for pep8 04:09:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:09:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:09:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:09:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:09:06 INFO - no previously-included directories found matching 'docs/_build' 04:09:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:09:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:09:06 INFO - Cleaning up... 04:09:07 INFO - Return code: 0 04:09:07 INFO - Running post-action listener: _resource_record_post_action 04:09:07 INFO - Running post-action listener: _start_resource_monitoring 04:09:07 INFO - Starting resource monitoring. 04:09:07 INFO - ##### 04:09:07 INFO - ##### Running install step. 04:09:07 INFO - ##### 04:09:07 INFO - Running pre-action listener: _resource_record_pre_action 04:09:07 INFO - Running main action method: install 04:09:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:07 INFO - Reading from file tmpfile_stdout 04:09:07 INFO - Detecting whether we're running mozinstall >=1.0... 04:09:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:09:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:09:07 INFO - Reading from file tmpfile_stdout 04:09:07 INFO - Output received: 04:09:07 INFO - Usage: mozinstall [options] installer 04:09:07 INFO - Options: 04:09:07 INFO - -h, --help show this help message and exit 04:09:07 INFO - -d DEST, --destination=DEST 04:09:07 INFO - Directory to install application into. [default: 04:09:07 INFO - "/builds/slave/test"] 04:09:07 INFO - --app=APP Application being installed. [default: firefox] 04:09:07 INFO - mkdir: /builds/slave/test/build/application 04:09:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:09:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:09:40 INFO - Reading from file tmpfile_stdout 04:09:40 INFO - Output received: 04:09:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:09:40 INFO - Running post-action listener: _resource_record_post_action 04:09:40 INFO - ##### 04:09:40 INFO - ##### Running run-tests step. 04:09:40 INFO - ##### 04:09:40 INFO - Running pre-action listener: _resource_record_pre_action 04:09:40 INFO - Running main action method: run_tests 04:09:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:09:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:09:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:09:40 INFO - 2016-04-22 04:09:40.487 screenresolution[2235:23644] starting screenresolution argv=screenresolution get 04:09:40 INFO - 2016-04-22 04:09:40.490 screenresolution[2235:23644] Display 0: 1600x1200x32@60 04:09:40 INFO - 2016-04-22 04:09:40.496 screenresolution[2236:23646] starting screenresolution argv=screenresolution list 04:09:40 INFO - Available Modes on Display 0 04:09:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:09:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:09:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:09:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:09:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:09:40 INFO - Intel Iris: 04:09:40 INFO - Chipset Model: Intel Iris 04:09:40 INFO - Type: GPU 04:09:40 INFO - Bus: Built-In 04:09:40 INFO - VRAM (Dynamic, Max): 1536 MB 04:09:40 INFO - Vendor: Intel (0x8086) 04:09:40 INFO - Device ID: 0x0a2e 04:09:40 INFO - Revision ID: 0x0009 04:09:40 INFO - Displays: 04:09:40 INFO - Display: 04:09:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:09:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:09:40 INFO - Display Serial Number: 1600x1200 60 04:09:40 INFO - Main Display: Yes 04:09:40 INFO - Mirror: Off 04:09:40 INFO - Online: Yes 04:09:40 INFO - Rotation: Supported 04:09:40 INFO - Return code: 0 04:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:09:40 INFO - Python 2.7.3 04:09:40 INFO - Return code: 0 04:09:40 INFO - grabbing minidump binary from tooltool 04:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:09:40 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 0x10cf39e00>, '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 0x10cd62db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cf34b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:09:40 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 04:09:40 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 04:09:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:09:40 INFO - Return code: 0 04:09:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:09:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:09:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:09:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:09:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:09:42 INFO - Return code: 0 04:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 04:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 04:09:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FL1Ufu35e0/Render', 04:09:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:09:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:09:42 INFO - 'HOME': '/Users/cltbld', 04:09:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:09:42 INFO - 'LOGNAME': 'cltbld', 04:09:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:42 INFO - 'MOZ_NO_REMOTE': '1', 04:09:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:42 INFO - 'NO_EM_RESTART': '1', 04:09:42 INFO - 'PAGER': '/bin/cat', 04:09:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:42 INFO - 'PWD': '/builds/slave/test', 04:09:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:09:42 INFO - 'SHELL': '/bin/bash', 04:09:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.d5oSEeQ3rf/Listeners', 04:09:42 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 04:09:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:09:42 INFO - 'USER': 'cltbld', 04:09:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:09:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:42 INFO - 'XPC_FLAGS': '0x0', 04:09:42 INFO - 'XPC_SERVICE_NAME': '0', 04:09:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:09:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 04:09:42 INFO - mozversion application_buildid: 20160422014259 04:09:42 INFO - mozversion application_changeset: b813b1e6e7e3288a865965bb9beefbf9d36b4a67 04:09:42 INFO - mozversion application_display_name: Nightly 04:09:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:09:42 INFO - mozversion application_name: Firefox 04:09:42 INFO - mozversion application_remotingname: firefox 04:09:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:09:42 INFO - mozversion application_vendor: Mozilla 04:09:42 INFO - mozversion application_version: 48.0a1 04:09:42 INFO - mozversion platform_buildid: 20160422014259 04:09:42 INFO - mozversion platform_changeset: b813b1e6e7e3288a865965bb9beefbf9d36b4a67 04:09:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:09:42 INFO - mozversion platform_version: 48.0a1 04:09:42 INFO - using testdate: 1461323382 04:09:42 INFO - actual date: 1461323382 04:09:42 INFO - starting webserver on 'localhost:58187' 04:09:42 INFO - SUITE-START | Running 2 tests 04:09:42 INFO - TEST-START | dromaeo_css 04:09:42 INFO - operating with platform_type : mac_ 04:09:42 INFO - Initialising browser for dromaeo_css test... 04:09:42 INFO - TEST-INFO | started process 2254 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpgRVG45/profile http://localhost:58187/getInfo.html) 04:09:44 INFO - PROCESS | 2254 | __metrics Screen width/height:1600/1200 04:09:44 INFO - PROCESS | 2254 | colorDepth:24 04:09:44 INFO - PROCESS | 2254 | Browser inner width/height: 1024/689 04:09:44 INFO - PROCESS | 2254 | __metrics 04:09:44 INFO - PROCESS | 2254 | 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" 04:09:44 ERROR - PROCESS | 2254 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:09:45 INFO - TEST-INFO | 2254: exit 0 04:09:45 INFO - Browser initialized. 04:09:45 INFO - Running cycle 1/1 for dromaeo_css test... 04:09:45 INFO - TEST-INFO | started process 2256 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpgRVG45/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:57 INFO - PROCESS | 2256 | RSS: Main: 238739456 04:09:57 INFO - PROCESS | 2256 | 04:10:48 INFO - PROCESS | 2256 | 1461323448126 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] 04:13:00 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58187/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58187/tests/dromaeo/cssquery-ext.html) 04:13:01 INFO - PROCESS | 2256 | RSS: Main: 389308416 04:13:01 INFO - PROCESS | 2256 | 04:16:04 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58187/tests/dromaeo/cssquery-ext.html (next: http://localhost:58187/tests/dromaeo/cssquery-jquery.html) 04:16:05 INFO - PROCESS | 2256 | RSS: Main: 382582784 04:16:05 INFO - PROCESS | 2256 | 04:19:07 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58187/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58187/tests/dromaeo/cssquery-mootools.html) 04:19:08 INFO - PROCESS | 2256 | RSS: Main: 378798080 04:19:08 INFO - PROCESS | 2256 | 04:22:10 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58187/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58187/tests/dromaeo/cssquery-prototype.html) 04:22:11 INFO - PROCESS | 2256 | RSS: Main: 378114048 04:22:11 INFO - PROCESS | 2256 | 04:25:14 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58187/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58187/tests/dromaeo/cssquery-yui.html) 04:25:15 INFO - PROCESS | 2256 | RSS: Main: 353071104 04:25:15 INFO - PROCESS | 2256 | 04:28:18 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58187/tests/dromaeo/cssquery-yui.html (next: http://localhost:58187/tests/dromaeo/cssquery-dojo.html) 04:28:18 INFO - PROCESS | 2256 | RSS: Main: 385986560 04:28:18 INFO - PROCESS | 2256 | 04:28:18 INFO - PROCESS | 2256 | __start_tp_report 04:28:18 INFO - PROCESS | 2256 | _x_x_mozilla_page_load 04:28:18 INFO - PROCESS | 2256 | _x_x_mozilla_page_load_details 04:28:18 INFO - PROCESS | 2256 | |i|pagename|runs| 04:28:18 INFO - PROCESS | 2256 | |0;dojo.html;3369.8315084245787;3419.5725534308212;3445.2592692571093;3457.827108644567;3469.2367679110603;5624.859378515539;5671.517920976717;5742.253507044084;5746.741396637156;5747.712614369275;5681.403452637465;5749.396313387097;5758.3665796762425;5762.827115186541;5762.942370576293;4728.71627702339;4760.8095676173;5167.7932882684645;5172.793088276464;5248.632595718302;5247.291615631898;5252.868678283055;5253.500917412859;5254.553362964152;5259.342582177228;5181.818636347729;5212.400573934008;5240.109181439165;5250.973745131269;5318.893622127555;5246.317978662769;5256.658317209369;5271.65734227274;5279.762410691528;5319.202119682079;5103.515166059218;5139.357580302462;5157.6647517911215;5193.039287731744;5194.35070616173;5642.661440313554;5663.631863928831;5690.9146362804595;5693.117566777156;5749.137629355589;6228.781992630282;6270.65511396873;6273.62358258506;6276.3409841966395;6281.057841323793;6026.819195424145;6128.264608246995;6189.845253868644;6316;6317.6209427434505;5772.826815195551;5790.739416726257;5802.1296805479515;5817.825465236049;5819.67991760453;6278.30938596754;6295.874082518324;6298.779542715983;6321.715522801485;6324.557280990287;6070.150178974947;6098.176746139303;6109.663968481687;6133.82435864808;6142.109394137825;6251.749930002749;6311.179546658905;6320.4311611955145;6325.936740632535;6353.174087368612;6189.783357582463;6236.314005459396;6249.625022498664;6272.3100458949475;6275.6548389839;6134.601250918676;6184.5052395808225;6185.876282474415;6242.812715618538;6300.873982520416;4918.1884988977135;4947.010597880367;4951.554360107608;4960.70235785854;4972.428170760337;4464.598186163261;4500.819967201275;4506.2564676828615;4513.81944722214;4525.366448697185;5699.829005129853;5722.656640601494;5728.942710572925;5736.77052917887;5789.971050144806;6246.531510136758;6291.433770960635;6310;6312.810615681537;6348.492120630339;5743.511801496836;5763.394843541451;5771.538276937835;5790.073588225863;5829.912551311733;5336.279602253724;5407.351117865881;5434.456554344534;5437.075697131497;5444.101723215705;6337.271213810379;6387.648679322496;6390.392912673288;6404.519661025349;6404.871902561923;6287.528435367457;6316.210473690789;6323.905141422974;6332.873342533216;6344.587601805868;5363.839084827461;6207.6585787782105;6364.236291645032;6367.42693157618;6379.043143528508;6229.844253893689;6233.127362169391;6246.312905580474;6253.09330147117;6259.906101408574;6135.509159267337;6259.530535209787;6280.874382512416;6301.149344838388;6327.525435592257;6005.189299444519;6070.817875463743;6104.786332478342;6120.233815636221;6147.170132032118;6110.229009153497;6136.864680034208;6141.486007784186;6148.9692551536955;6157.89157951573;5963.314218864886;5986.491148252273;5991.580589358878;6020.6989650516425;6034.668093254913;5960.195373624679;6093.9085913712215;6121.938780612137;6160.661163636043;6166.691665416622;6037.69811509414;6052.425019623129;6054.091886217103;6071.3625069368845;6081.847953801191 04:28:18 INFO - PROCESS | 2256 | |1;ext.html;488.6335248563577;511.9734931462417;513.4198355857881;525.5270256768907;577.7602295047554;29818;30453.390932181344;30556.84721576389;37590.0602484938;38954.220915581674;41326.17347653045;42165.15669686604;42193.7890310548;42238;42251;9474.431534107942;9663.178629816473;9674.854877176847;9682.12860842524;9687.418754874694;8555.230029297361;8835.469871807712;9490.715278541655;11483.311001339904;11854.933056024956;11486.310821350704;11608.59369922053;11609.361485118316;11615.941920290386;11620.0122989546;8531.232189102948;11438;11546.018588419995;11556.422178891022;11583.710407239845;8661.480311181349;8776.692815751418;8784.253338466238;11308.886911130865;11714.472848721827;30393.24016899595;38833.22333553341;38917.83246502609;38935.41596876049;38976.61023389758;2948.3661012882335;3283.0315057058224;3371.96628033719;3766.453864189677;3869.7484663497066;24192.637110443357;26068.34829129287;28288.868445262164;28294.717052829415;28318.575221371695;2645.378336091017;2680.1691475642615;2826.095649392175;2829.4199689063694;2835.6313679221776;425291.8735406334;437955.43066854344;438484.4227336593;441326;442731;60964;61493.38506614966;61779;61925.38074619286;62060.37939620546;64721.352786472475;65178.674106629165;65246;65288.347116528224;65329;16467.917660411862;17211;17239.655206895794;17247.396341128002;17272.05003724782;11949.701257468632;12265;13173.802392964113;13243.536476223286;13259.73480530384;16396;16567;16698.24857881374;18881.81118188828;19539.316123935594;6426.786606696652;6533.346665333524;6837.940119281519;6963.7562685305775;7730.845383092306;982.6904525074578;1004.457592899826;1017.2777328097134;1024.7950409918046;1065.9093977011885;23921.641175382385;29380;29482.968096116532;29491;29794.70205297919;1092.0771947704336;1106.4523061084653;1129.373197875187;1146.4382452598168;1214.40494157862;15047.473338433321;15262.923685381724;15263.847361526465;15674.216289185495;15757.369705211893;637675;638028;645775;646938;651947;59819.40180598138;60314.69842650759;60503.09245361235;60591.39408605946;60641;1427.772115980256;1463.5024091808837;1474.2555009720022;1477.4976507987121;1478.36430334957;24626.50746985086;28629;28802.567961481014;28817.559122044233;29488.557671634513;50204.24693629672;50260.74869625628;50748.238776417646;50944.98110037853;51027;570668;578328;578790;580137;580367;21582.784172158077;23290.951907164555;23844.403889902893;26183;26641;31623.57693903826;42026.57973420226;45496.31755523603;48023.279650804565;48525.757371212916;32142.874999374908;34505.526338133306;34616.13459663529;36969.44545831868;36986.26027479489;34741.826290869394;35200.82399587986;35222.76720314776;35940;36059.81970090133;35230;35548.11129721778;36029.63970360263;36040.098997525274;36162;31834.363312733152;31970.52044219292;32548.51177232296;32578.185545361557;32669.836650816593;21428.250011249533;21563.784362156177;24308;24454.87772561126;24759.87620061888 04:28:18 INFO - PROCESS | 2256 | |2;jquery.html;370.707141358327;387.5330227076373;400.6714494114826;406.85556627397256;411.30900087852393;13932.651683707887;14909.701805963874;19473.3184338548;20447.591048179027;20449.488762780937;16076.919615401936;18327.54181145472;20083.59832803343;20547.486312842186;20936.057877395517;5677.318721753385;5867.3252575953675;5867.325257595388;5870.647761134345;5872.500837428803;5830;5866.266716660418;5869.85325366865;7307.561546307212;7819.843603127934;4662;4956.3804524434445;7428.368588669942;7802.33680137189;7855.41084418668;7697.5381477111;7782.727604533842;7801.141874393812;7808.219178082203;7830.921690783076;7262.382697470722;7346.37555807757;7568;7601.885971710429;7616.200298968584;26664.20007399781;26683.866580667167;27046.72953270462;27107.45785084287;27808.86095569509;2148.6669566217106;2181.9563608727735;2234;2287.7254729432575;3039.37692772981;12684.492620295177;12811.103222774409;15608.531744047697;16012.83987160125;16455.177241137895;1850.241401025573;1852.7869295030973;1870.1584287070737;1873.681474149398;1950.619629172312;122364;127700.7229927698;129713;130810.3459482706;134701.32649336787;20075.29736459217;20655.69016464754;20732;20839.374818755463;20844.47888802792;35741.28517429637;35817.64182358195;35851.10372240715;35863.641363586554;35901.28197436037;10612.734681633021;10881.564737410416;10923.726906827234;11075.11399088071;11286.98417142461;8957.462552246887;8969.013408525057;9023.368364214573;9079.001309856043;9130.086991300817;9289.581968811419;9469.621215151454;9549.71078904339;9553.856692149624;9558;3028.9394212115635;3584.7849129052343;3711.498947642088;3786.497489821904;3796.468494410785;920.7191806499083;937.8358787212211;970.7233438470143;972.4651441707033;1056.9947150264354;17993;19286.71069933952;19342;19429.708554371697;19706.507337316394;942.1661829280998;972.2902281334665;1008.7780688248573;1011.8988101189822;1083.1389045708681;9843.212542996544;10475.1096156826;10541.367517948947;10564.524596393181;10565.408217981078;570221;584526;585318;597993;599189;97360.53959190618;123081;126654.36672816762;127111;132397.70409183524;837.351052933981;1020.7295066807302;1044.7335929337971;1045.0855501436242;1063.4948399510172;17025.233864475882;17456.301747930447;17688.55778605545;17755.911220443813;17840.821591783915;51238.74380628073;52138.73930630323;53544.73227633837;54086.45913540814;55257;68047.65976170088;78873;80634;81325;82902.75645865437;7847.5089732950555;8001.359891208573;8153;8159.26566609008;8403.95798021006;10306.381617102998;11861.229020113666;12608.621741347773;12674.302913339487;12800.295983720613;10247.692569222936;10661.360318380923;10966.72740433804;10990.631117043682;11179.329240245612;10391.636292729718;10556.630517931835;10732.73168170802;10986.285891417032;11028;11190.32858028521;11216.214864959375;11331.150163737917;11404.973552380327;11547.364894930859;7881.330086942674;8051.758447246592;8157.1842815717955;8240.464369815943;8392.748217553482;8153.429259951984;10078.143357814668;10810.729731756768;11326.773464530499;11375.20373573842 04:28:18 INFO - PROCESS | 2256 | |3;mootools.html;1338.872807083327;1380.4961189165945;1567.2320562924172;1754.8508376787972;1968.045497933503;7854;8590.226879580836;8838.292936565083;8856.645734170625;8857.424267422613;8416.747497575067;8650.307975361971;8661.133886611336;8672.956635216831;8677.783055423617;2098.3075585056895;2383.7139543254834;2415.625578035407;2457.066314800373;2476.0838489758726;2447.7552244775557;2469.8641574713383;2471.5921872890954;2476.1209770531473;2479.417336925821;2406.3142004528627;2425.490646964131;2430.6597076409316;2435.3424575364643;2436.719777225624;2113.5033267182125;2335.065973610552;2400.1719406804623;2421.0315873650507;2421.5278020785954;2387.164492427654;2420.3586049696846;2422.0917156066475;2422.7213870404953;2423.248792874197;8591.26974207193;8652.78368040804;8658.78353041179;8662.91713535808;8672.739817805414;5046.899062018776;5188.766505507224;5244.344456942882;5289.682619042831;5310.70791106489;3440.9655903440894;3533.063738109403;3542.8937131886087;3578.3737845876867;3600.4419315006216;1012.3622118065645;1024.9795004099876;1029.7837454134565;1033.8500917367014;1046.947652617366;9283.814323713555;11503.07975361969;11675.824862627067;11690.123240756891;11741.4716337765;4442.067165895133;4537.319402089713;4685.320628508847;4693.24908014716;4703.741294228782;9177.587008584629;9183.40307879999;9194;9196.862047069299;9205.309601779893;5243.580513558983;5251.76367063483;5253.632245742761;5276.498732620395;5282.947170528246;4378.452693413324;4549.545045495424;4700.153972285021;4855.902881942412;4861.927071093936;3496.4056110461283;3733;3844.2888065707934;3849.345611246095;3856.633619806114;1209.5464200924653;1217.896478799312;1217.9634610961684;1226.405193481161;1228.4963165102265;835.3943391041568;837.794740288621;872.9524570515407;919.3426699909596;975.1516180922642;5682.516986056149;5708.80019199326;5896.351401345849;6030.556816424639;6065.484433823086;929.4934260827865;965.7006328038331;975.0152346130459;990.2226751999646;1016.7254841192985;3858.9421158682644;3949.1311911379453;3990.4014397840556;4024;4024.1348110155873;9655.903440965641;10288.691339259834;10599.470026498799;10907.781844363068;11098.223124381217;10876.72808179786;10995.065419439277;11026.44867756609;11055.834162487568;11065.50205240766;8130.674773009014;8167.959160204161;8195.098539160688;8270.627821748036;8273.22125742956;5797.9420205797405;5809.302883653989;5864.296284445893;6028.2766068072115;6104.633721976696;9694.224462043161;9746.463944483121;9869.605215791287;9939.105480506789;10037.949810250902;10068.949655251678;10085.445300508543;10125.341852779546;10218.437985910548;10274.33216840903;6633.433927140723;6902.965485172743;7157;7360.852782944204;7538.6607602659105;7813.531188128731;7905.130435652189;7925.484843485155;8431.915680843113;8436.11420800807;7341.3392794648735;7452.217517160837;7479.439042071757;7605.6577454013595;7690.192529784292;6952.096227490394;7259.346658800733;7297.817554561178;7400.740974065882;7419.629018549159;6907.20567134786;7101.502894797314;7135.357817796215;7145.249748776304;7160.820979475555;4804.591609713074;4861.246506791453;4878.487758785276;4908.852734417973;5130.999455106367;4915.729634869974;4969.975150124226;4982.4768399317545;5024.748762561784;5186.611004174777 04:28:18 INFO - PROCESS | 2256 | |4;prototype.html;323.0647276135588;330.54054863739407;334.77068208277325;339.44670187594215;343.1780884780948;5952.166696662465;6009.218801555797;6091.451769340759;6092.756289748405;6135.417135372144;5612.831615051545;5944.464998150165;6016.8495787605225;6031.471589120359;6047.5766696331275;3905.9023524411973;4267.295896177135;4375.453068366454;4389.319655453394;4400.647948164155;4380.408644832954;4451.866444006685;4477.776111194427;4493.168763778712;4506.5493450655;4446.2219111655495;4469.82120715171;4475;4484.349769283452;4490.979059423256;3665.2669466106886;4263.104748002923;4375.453068366454;4467.977660111711;4477.485174138285;3206.502992036238;3517.120719820045;3547;3872.380419132925;3876.3022655921923;5402.567794576425;5692;5696.401877802812;5720.2849643794525;5722.938228047974;5083.847484575469;5218.225803226883;5224.216367544898;5233.633645644806;5269.920951185735;5275.498827611371;5551.278333816618;6357.777477788301;6401.327860574619;6406.711697973602;4215.578442155791;4216.7259128156575;4334.154839806239;4352.673549483801;4359.215341238576;5030.622703297267;6393.328700486429;6602.768903088417;6689.8662026759675;6724.293949135319;4364.127174565099;4364.6944713869725;4368.541303163122;4380.824767009284;4410.42664453619;3770.321342158438;4487.528809475023;4489.775511224426;4492.505824359318;4495.865124046284;4585;5036.244563315532;5209.895802083937;5222.4255331913455;5261.394939581921;6405.455536279375;6422.325655806167;6432.614043157426;6440.13058237132;6441.194850643669;1473.3001824133664;1522.6802371501888;1543.3672194400242;1543.4597890738153;1547.5744170353244;864.8400045991386;1005.7234260578399;1022.8823685276288;1027.4554486122368;1031.5254982707888;5967.582269241198;5987.353477793642;6007.158997740233;6022.759089636453;6054.3945605439985;6355.697082098159;6608.207015158116;6647.734090636417;6650.86698266032;6655.73377064912;5277.498637629419;5476.123820188751;5842.954965761949;6050.6672133032225;6093.207882975274;3418.5555877735737;3467.4625433057918;3469.6344545790544;3473.4616134499192;3499.825008749552;5947.226860508193;6525.3474652535315;6889.862202755917;6890.276520965226;6921.515493915377;5454.836354909359;6204.81385558434;6251.374862513712;6795.3204679531655;6879.346462086094;6132.6320420774;6822.33928767781;6875.862482750417;6911.377975982085;6911.75808846688;5745;5824.621399609012;6046.939530604637;6594.208694956636;6595.967020164869;5212.296339994204;5621.763212093333;5806.970965145148;6217;6510.804675859732;5948.73230704628;6466.062420948842;6824.385805277549;6941.618210998444;6952.374286314256;3235.822029788384;3600.3159399713973;4278.850240241577;4288.31386978082;4290.141971605629;6348.682565871595;6648.6010839347855;6670.232923213893;6689.2307384651385;6725.562838415487;6419.711112999833;6438.67806609677;6459.095726598186;6466.935330646634;6473.320301368289;5402.594805389533;5501.927124210741;5561.332640083216;6167.321594624677;6358.263093995003;5058.974705126451;6248.4688801452385;6254.155688981929;6291.465225455888;6338.968305158444;6071.908921366271;6159.599626024294;6465.191851018622;6465.773697920738;6477.222733272038;5206.427292997691;6310.968445157745;6323.936760632335;6359.908701797555;6366.267879193952 04:28:18 INFO - PROCESS | 2256 | |5;yui.html;632.4276529740584;668.6523008035822;716.7455553278586;743.5241445474894;763.9312461878429;14741.631459213526;14960.401583936644;14963.027403218783;14987.625309367271;15038.172900490468;13571.52499662512;13835.446582136728;13911.582652520441;14250;14297.642558936031;925.8055808280269;926.9953650231739;946.0066929723797;947.554649314821;947.9383840050409;727.396261103283;738.6048464071723;898.9505577193252;932.7807965128189;939.5255396024997;984.4585477987114;986.270160081538;986.4377304936189;988.4810474500908;989.7426669066059;755.5768769489049;981.087588542655;981.2787601113176;981.4994352880046;982.40564458503;707.1584814071239;730.0800990751691;732.1251104929611;732.3372348025046;735.400648471494;15312.923435382752;15328.463503777426;15344.846551534454;15435.30541125652;15933.123678197695;10782.299150555267;12202.572909948109;12972;12989.285589292585;13349.599512014654;9769.609215631366;10036.749081272954;10168.847467287997;10593.470326483644;10821.621243256526;3587.515685382466;3657.5976642569294;3687.870924517629;3779.3764028935193;3800.7529510581726;76009.09977250558;76924;77304.84042739363;78330.65008049768;83526.58236708777;9185.678501252423;9290.44257344548;9436.858447123299;9628.18160456369;9787.021297870157;21107.472313192295;21155.78844211569;21167;21184.881511848693;21218.575628487342;6533.673316334164;7352.669129889167;7418.035655364768;7452.962735186289;7484.47608667399;3935.2916475034767;4034.717569770147;4045.696572757055;4052.230076285496;4249.575042495726;3606.314800187956;3696.667299943018;3700.833462494194;3702.148505843671;3704.6110158433517;559.3846768554639;629.0846817879974;647.0326861342323;649.6719156825773;653.8038588423454;522.5949888836183;583.3379114704841;601.7983975368213;610.4505944649762;634.403660559073;7845.1762564931005;7970.760877173694;7981;8071.717489887826;8239.423240373117;568.8919105369966;584.1352817710142;597.8624916269282;609.5550248318752;613.3069631316583;3644.2711457708565;3769.830357633913;3780.0360907968293;3879.903002424962;3917.490726205575;87515;89458;89943.55028224816;90153;90233.54883225542;74228;74654.62672686602;75867;77108.61445692736;78235.60882195666;65042.34957650457;65106.88503609499;65228.78169746409;65516;67166.32833671698;7380.483366164317;7457.366123879531;7503.812404689708;7698.576578288248;7848.60756962161;45075.774621126686;45249;45277.547224527334;45368;46074;63276.78531251384;64858;64861.67569162124;65113.37216569624;65493.672531637036;10015.39907605546;10058.8491172631;10076.24428167905;10254.897451025692;10392.58429662805;9651.324407291422;10771.784564308826;10855.620053298097;11192.888071119185;11339.943300283447;10217.846732299171;10233.846492302473;10324.587016519255;10384.32501887375;10631.362118272928;8874.378793484395;10168.542415591168;10184;10311.123554497652;10323.432211228455;9305.860412093689;9675.564599592895;10001.599936002478;10084.899151008396;10485.9475702621;7622.237776222333;8274.58627068632;8616.698415555426;8803;8858.066669655798;8666.826663466822;8776.078511756174;8854.557272136497;8922.643094276156;9026.142516460926 04:28:18 INFO - PROCESS | 2256 | __end_tp_report 04:28:18 INFO - PROCESS | 2256 | __start_cc_report 04:28:18 INFO - PROCESS | 2256 | _x_x_mozilla_cycle_collect,1451 04:28:18 INFO - PROCESS | 2256 | __end_cc_report 04:28:18 INFO - PROCESS | 2256 | __startTimestamp1461324498255__endTimestamp 04:28:18 INFO - PROCESS | 2256 | 04:28:18 INFO - PROCESS | 2256 | ------- Summary: start ------- 04:28:18 INFO - PROCESS | 2256 | Number of tests: 6 04:28:18 INFO - PROCESS | 2256 | 04:28:18 INFO - PROCESS | 2256 | [#0] dojo.html Cycles:165 Average:5768.89 Median:6026.82 stddev:640.73 (10.6%) stddev-sans-first:614.43 04:28:18 INFO - PROCESS | 2256 | Values: 3369.8 3419.6 3445.3 3457.8 3469.2 5624.9 5671.5 5742.3 5746.7 5747.7 5681.4 5749.4 5758.4 5762.8 5762.9 4728.7 4760.8 5167.8 5172.8 5248.6 5247.3 5252.9 5253.5 5254.6 5259.3 5181.8 5212.4 5240.1 5251.0 5318.9 5246.3 5256.7 5271.7 5279.8 5319.2 5103.5 5139.4 5157.7 5193.0 5194.4 5642.7 5663.6 5690.9 5693.1 5749.1 6228.8 6270.7 6273.6 6276.3 6281.1 6026.8 6128.3 6189.8 6316.0 6317.6 5772.8 5790.7 5802.1 5817.8 5819.7 6278.3 6295.9 6298.8 6321.7 6324.6 6070.2 6098.2 6109.7 6133.8 6142.1 6251.7 6311.2 6320.4 6325.9 6353.2 6189.8 6236.3 6249.6 6272.3 6275.7 6134.6 6184.5 6185.9 6242.8 6300.9 4918.2 4947.0 4951.6 4960.7 4972.4 4464.6 4500.8 4506.3 4513.8 4525.4 5699.8 5722.7 5728.9 5736.8 5790.0 6246.5 6291.4 6310.0 6312.8 6348.5 5743.5 5763.4 5771.5 5790.1 5829.9 5336.3 5407.4 5434.5 5437.1 5444.1 6337.3 6387.6 6390.4 6404.5 6404.9 6287.5 6316.2 6323.9 6332.9 6344.6 5363.8 6207.7 6364.2 6367.4 6379.0 6229.8 6233.1 6246.3 6253.1 6259.9 6135.5 6259.5 6280.9 6301.1 6327.5 6005.2 6070.8 6104.8 6120.2 6147.2 6110.2 6136.9 6141.5 6149.0 6157.9 5963.3 5986.5 5991.6 6020.7 6034.7 5960.2 6093.9 6121.9 6160.7 6166.7 6037.7 6052.4 6054.1 6071.4 6081.8 04:28:18 INFO - PROCESS | 2256 | 04:28:18 INFO - PROCESS | 2256 | [#1] ext.html Cycles:180 Average:68179.80 Median:18938.75 stddev:149742.02 (790.7%) stddev-sans-first:150075.84 04:28:18 INFO - PROCESS | 2256 | Values: 488.6 512.0 513.4 525.5 577.8 29818.0 30453.4 30556.8 37590.1 38954.2 41326.2 42165.2 42193.8 42238.0 42251.0 9474.4 9663.2 9674.9 9682.1 9687.4 8555.2 8835.5 9490.7 11483.3 11854.9 11486.3 11608.6 11609.4 11615.9 11620.0 8531.2 11438.0 11546.0 11556.4 11583.7 8661.5 8776.7 8784.3 11308.9 11714.5 30393.2 38833.2 38917.8 38935.4 38976.6 2948.4 3283.0 3372.0 3766.5 3869.7 24192.6 26068.3 28288.9 28294.7 28318.6 2645.4 2680.2 2826.1 2829.4 2835.6 425291.9 437955.4 438484.4 441326.0 442731.0 60964.0 61493.4 61779.0 61925.4 62060.4 64721.4 65178.7 65246.0 65288.3 65329.0 16467.9 17211.0 17239.7 17247.4 17272.1 11949.7 12265.0 13173.8 13243.5 13259.7 16396.0 16567.0 16698.2 18881.8 19539.3 6426.8 6533.3 6837.9 6963.8 7730.8 982.7 1004.5 1017.3 1024.8 1065.9 23921.6 29380.0 29483.0 29491.0 29794.7 1092.1 1106.5 1129.4 1146.4 1214.4 15047.5 15262.9 15263.8 15674.2 15757.4 637675.0 638028.0 645775.0 646938.0 651947.0 59819.4 60314.7 60503.1 60591.4 60641.0 1427.8 1463.5 1474.3 1477.5 1478.4 24626.5 28629.0 28802.6 28817.6 29488.6 50204.2 50260.7 50748.2 50945.0 51027.0 570668.0 578328.0 578790.0 580137.0 580367.0 21582.8 23291.0 23844.4 26183.0 26641.0 31623.6 42026.6 45496.3 48023.3 48525.8 32142.9 34505.5 34616.1 36969.4 36986.3 34741.8 35200.8 35222.8 35940.0 36059.8 35230.0 35548.1 36029.6 36040.1 36162.0 31834.4 31970.5 32548.5 32578.2 32669.8 21428.3 21563.8 24308.0 24454.9 24759.9 04:28:18 INFO - PROCESS | 2256 | 04:28:18 INFO - PROCESS | 2256 | [#2] jquery.html Cycles:180 Average:36061.66 Median:26865.30 stddev:98080.54 (365.1%) stddev-sans-first:98319.07 04:28:18 INFO - PROCESS | 2256 | Values: 370.7 387.5 400.7 406.9 411.3 13932.7 14909.7 19473.3 20447.6 20449.5 16076.9 18327.5 20083.6 20547.5 20936.1 5677.3 5867.3 5867.3 5870.6 5872.5 5830.0 5866.3 5869.9 7307.6 7819.8 4662.0 4956.4 7428.4 7802.3 7855.4 7697.5 7782.7 7801.1 7808.2 7830.9 7262.4 7346.4 7568.0 7601.9 7616.2 26664.2 26683.9 27046.7 27107.5 27808.9 2148.7 2182.0 2234.0 2287.7 3039.4 12684.5 12811.1 15608.5 16012.8 16455.2 1850.2 1852.8 1870.2 1873.7 1950.6 122364.0 127700.7 129713.0 130810.3 134701.3 20075.3 20655.7 20732.0 20839.4 20844.5 35741.3 35817.6 35851.1 35863.6 35901.3 10612.7 10881.6 10923.7 11075.1 11287.0 8957.5 8969.0 9023.4 9079.0 9130.1 9289.6 9469.6 9549.7 9553.9 9558.0 3028.9 3584.8 3711.5 3786.5 3796.5 920.7 937.8 970.7 972.5 1057.0 17993.0 19286.7 19342.0 19429.7 19706.5 942.2 972.3 1008.8 1011.9 1083.1 9843.2 10475.1 10541.4 10564.5 10565.4 570221.0 584526.0 585318.0 597993.0 599189.0 97360.5 123081.0 126654.4 127111.0 132397.7 837.4 1020.7 1044.7 1045.1 1063.5 17025.2 17456.3 17688.6 17755.9 17840.8 51238.7 52138.7 53544.7 54086.5 55257.0 68047.7 78873.0 80634.0 81325.0 82902.8 7847.5 8001.4 8153.0 8159.3 8404.0 10306.4 11861.2 12608.6 12674.3 12800.3 10247.7 10661.4 10966.7 10990.6 11179.3 10391.6 10556.6 10732.7 10986.3 11028.0 11190.3 11216.2 11331.2 11405.0 11547.4 7881.3 8051.8 8157.2 8240.5 8392.7 8153.4 10078.1 10810.7 11326.8 11375.2 04:28:18 INFO - PROCESS | 2256 | 04:28:18 INFO - PROCESS | 2256 | [#3] mootools.html Cycles:180 Average:5653.94 Median:4976.23 stddev:3142.23 (63.1%) stddev-sans-first:3134.30 04:28:18 INFO - PROCESS | 2256 | Values: 1338.9 1380.5 1567.2 1754.9 1968.0 7854.0 8590.2 8838.3 8856.6 8857.4 8416.7 8650.3 8661.1 8673.0 8677.8 2098.3 2383.7 2415.6 2457.1 2476.1 2447.8 2469.9 2471.6 2476.1 2479.4 2406.3 2425.5 2430.7 2435.3 2436.7 2113.5 2335.1 2400.2 2421.0 2421.5 2387.2 2420.4 2422.1 2422.7 2423.2 8591.3 8652.8 8658.8 8662.9 8672.7 5046.9 5188.8 5244.3 5289.7 5310.7 3441.0 3533.1 3542.9 3578.4 3600.4 1012.4 1025.0 1029.8 1033.9 1046.9 9283.8 11503.1 11675.8 11690.1 11741.5 4442.1 4537.3 4685.3 4693.2 4703.7 9177.6 9183.4 9194.0 9196.9 9205.3 5243.6 5251.8 5253.6 5276.5 5282.9 4378.5 4549.5 4700.2 4855.9 4861.9 3496.4 3733.0 3844.3 3849.3 3856.6 1209.5 1217.9 1218.0 1226.4 1228.5 835.4 837.8 873.0 919.3 975.2 5682.5 5708.8 5896.4 6030.6 6065.5 929.5 965.7 975.0 990.2 1016.7 3858.9 3949.1 3990.4 4024.0 4024.1 9655.9 10288.7 10599.5 10907.8 11098.2 10876.7 10995.1 11026.4 11055.8 11065.5 8130.7 8168.0 8195.1 8270.6 8273.2 5797.9 5809.3 5864.3 6028.3 6104.6 9694.2 9746.5 9869.6 9939.1 10037.9 10068.9 10085.4 10125.3 10218.4 10274.3 6633.4 6903.0 7157.0 7360.9 7538.7 7813.5 7905.1 7925.5 8431.9 8436.1 7341.3 7452.2 7479.4 7605.7 7690.2 6952.1 7259.3 7297.8 7400.7 7419.6 6907.2 7101.5 7135.4 7145.2 7160.8 4804.6 4861.2 4878.5 4908.9 5131.0 4915.7 4970.0 4982.5 5024.7 5186.6 04:28:18 INFO - PROCESS | 2256 | 04:28:18 INFO - PROCESS | 2256 | [#4] prototype.html Cycles:180 Average:5138.35 Median:5708.34 stddev:1615.53 (28.3%) stddev-sans-first:1579.11 04:28:18 INFO - PROCESS | 2256 | Values: 323.1 330.5 334.8 339.4 343.2 5952.2 6009.2 6091.5 6092.8 6135.4 5612.8 5944.5 6016.8 6031.5 6047.6 3905.9 4267.3 4375.5 4389.3 4400.6 4380.4 4451.9 4477.8 4493.2 4506.5 4446.2 4469.8 4475.0 4484.3 4491.0 3665.3 4263.1 4375.5 4468.0 4477.5 3206.5 3517.1 3547.0 3872.4 3876.3 5402.6 5692.0 5696.4 5720.3 5722.9 5083.8 5218.2 5224.2 5233.6 5269.9 5275.5 5551.3 6357.8 6401.3 6406.7 4215.6 4216.7 4334.2 4352.7 4359.2 5030.6 6393.3 6602.8 6689.9 6724.3 4364.1 4364.7 4368.5 4380.8 4410.4 3770.3 4487.5 4489.8 4492.5 4495.9 4585.0 5036.2 5209.9 5222.4 5261.4 6405.5 6422.3 6432.6 6440.1 6441.2 1473.3 1522.7 1543.4 1543.5 1547.6 864.8 1005.7 1022.9 1027.5 1031.5 5967.6 5987.4 6007.2 6022.8 6054.4 6355.7 6608.2 6647.7 6650.9 6655.7 5277.5 5476.1 5843.0 6050.7 6093.2 3418.6 3467.5 3469.6 3473.5 3499.8 5947.2 6525.3 6889.9 6890.3 6921.5 5454.8 6204.8 6251.4 6795.3 6879.3 6132.6 6822.3 6875.9 6911.4 6911.8 5745.0 5824.6 6046.9 6594.2 6596.0 5212.3 5621.8 5807.0 6217.0 6510.8 5948.7 6466.1 6824.4 6941.6 6952.4 3235.8 3600.3 4278.9 4288.3 4290.1 6348.7 6648.6 6670.2 6689.2 6725.6 6419.7 6438.7 6459.1 6466.9 6473.3 5402.6 5501.9 5561.3 6167.3 6358.3 5059.0 6248.5 6254.2 6291.5 6339.0 6071.9 6159.6 6465.2 6465.8 6477.2 5206.4 6311.0 6323.9 6359.9 6366.3 04:28:18 INFO - PROCESS | 2256 | 04:28:18 INFO - PROCESS | 2256 | [#5] yui.html Cycles:180 Average:17524.70 Median:64859.84 stddev:24684.08 (38.1%) stddev-sans-first:24720.74 04:28:18 INFO - PROCESS | 2256 | Values: 632.4 668.7 716.7 743.5 763.9 14741.6 14960.4 14963.0 14987.6 15038.2 13571.5 13835.4 13911.6 14250.0 14297.6 925.8 927.0 946.0 947.6 947.9 727.4 738.6 899.0 932.8 939.5 984.5 986.3 986.4 988.5 989.7 755.6 981.1 981.3 981.5 982.4 707.2 730.1 732.1 732.3 735.4 15312.9 15328.5 15344.8 15435.3 15933.1 10782.3 12202.6 12972.0 12989.3 13349.6 9769.6 10036.7 10168.8 10593.5 10821.6 3587.5 3657.6 3687.9 3779.4 3800.8 76009.1 76924.0 77304.8 78330.7 83526.6 9185.7 9290.4 9436.9 9628.2 9787.0 21107.5 21155.8 21167.0 21184.9 21218.6 6533.7 7352.7 7418.0 7453.0 7484.5 3935.3 4034.7 4045.7 4052.2 4249.6 3606.3 3696.7 3700.8 3702.1 3704.6 559.4 629.1 647.0 649.7 653.8 522.6 583.3 601.8 610.5 634.4 7845.2 7970.8 7981.0 8071.7 8239.4 568.9 584.1 597.9 609.6 613.3 3644.3 3769.8 3780.0 3879.9 3917.5 87515.0 89458.0 89943.6 90153.0 90233.5 74228.0 74654.6 75867.0 77108.6 78235.6 65042.3 65106.9 65228.8 65516.0 67166.3 7380.5 7457.4 7503.8 7698.6 7848.6 45075.8 45249.0 45277.5 45368.0 46074.0 63276.8 64858.0 64861.7 65113.4 65493.7 10015.4 10058.8 10076.2 10254.9 10392.6 9651.3 10771.8 10855.6 11192.9 11339.9 10217.8 10233.8 10324.6 10384.3 10631.4 8874.4 10168.5 10184.0 10311.1 10323.4 9305.9 9675.6 10001.6 10084.9 10485.9 7622.2 8274.6 8616.7 8803.0 8858.1 8666.8 8776.1 8854.6 8922.6 9026.1 04:28:18 INFO - PROCESS | 2256 | -------- Summary: end -------- 04:28:18 INFO - PROCESS | 2256 | 04:28:18 INFO - TEST-INFO | 2256: exit 0 04:28:18 INFO - TEST-OK | dromaeo_css | took 1116571ms 04:28:18 INFO - TEST-START | kraken 04:28:18 INFO - operating with platform_type : mac_ 04:28:18 INFO - Initialising browser for kraken test... 04:28:18 INFO - TEST-INFO | started process 2258 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpm91SGX/profile http://localhost:58187/getInfo.html) 04:28:21 INFO - PROCESS | 2258 | __metrics Screen width/height:1600/1200 04:28:21 INFO - PROCESS | 2258 | colorDepth:24 04:28:21 INFO - PROCESS | 2258 | Browser inner width/height: 1024/689 04:28:21 INFO - PROCESS | 2258 | __metrics 04:28:21 INFO - PROCESS | 2258 | 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" 04:28:21 ERROR - PROCESS | 2258 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:28:21 INFO - TEST-INFO | 2258: exit 0 04:28:21 INFO - Browser initialized. 04:28:21 INFO - Running cycle 1/1 for kraken test... 04:28:21 INFO - TEST-INFO | started process 2260 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpm91SGX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:33 INFO - PROCESS | 2260 | RSS: Main: 238915584 04:28:33 INFO - PROCESS | 2260 | 04:28:36 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58187/tests/kraken/driver.html?testName=audio-beat-detection) 04:28:37 INFO - PROCESS | 2260 | RSS: Main: 287064064 04:28:37 INFO - PROCESS | 2260 | 04:28:39 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58187/tests/kraken/driver.html?testName=audio-dft) 04:28:40 INFO - PROCESS | 2260 | RSS: Main: 299540480 04:28:40 INFO - PROCESS | 2260 | 04:28:44 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58187/tests/kraken/driver.html?testName=audio-fft) 04:28:44 INFO - PROCESS | 2260 | RSS: Main: 385826816 04:28:44 INFO - PROCESS | 2260 | 04:28:46 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58187/tests/kraken/driver.html?testName=audio-oscillator) 04:28:47 INFO - PROCESS | 2260 | RSS: Main: 285466624 04:28:47 INFO - PROCESS | 2260 | 04:28:49 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58187/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:28:49 INFO - PROCESS | 2260 | RSS: Main: 290177024 04:28:49 INFO - PROCESS | 2260 | 04:28:52 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58187/tests/kraken/driver.html?testName=imaging-darkroom) 04:28:53 INFO - PROCESS | 2260 | RSS: Main: 329318400 04:28:53 INFO - PROCESS | 2260 | 04:28:56 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58187/tests/kraken/driver.html?testName=imaging-desaturate) 04:28:56 INFO - PROCESS | 2260 | RSS: Main: 330207232 04:28:56 INFO - PROCESS | 2260 | 04:28:59 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58187/tests/kraken/driver.html?testName=json-parse-financial) 04:29:00 INFO - PROCESS | 2260 | RSS: Main: 328327168 04:29:00 INFO - PROCESS | 2260 | 04:29:02 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58187/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:29:02 INFO - PROCESS | 2260 | RSS: Main: 320184320 04:29:02 INFO - PROCESS | 2260 | 04:29:04 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58187/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:29:05 INFO - PROCESS | 2260 | RSS: Main: 327487488 04:29:05 INFO - PROCESS | 2260 | 04:29:07 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58187/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:29:08 INFO - PROCESS | 2260 | RSS: Main: 329789440 04:29:08 INFO - PROCESS | 2260 | 04:29:10 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:29:11 INFO - PROCESS | 2260 | RSS: Main: 334635008 04:29:11 INFO - PROCESS | 2260 | 04:29:14 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:29:15 INFO - PROCESS | 2260 | RSS: Main: 332103680 04:29:15 INFO - PROCESS | 2260 | 04:29:16 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:58187/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58187/tests/kraken/driver.html?testName=ai-astar) 04:29:17 INFO - PROCESS | 2260 | RSS: Main: 397942784 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | __start_tp_report 04:29:17 INFO - PROCESS | 2260 | _x_x_mozilla_page_load 04:29:17 INFO - PROCESS | 2260 | _x_x_mozilla_page_load_details 04:29:17 INFO - PROCESS | 2260 | |i|pagename|runs| 04:29:17 INFO - PROCESS | 2260 | |0;ai-astar;110;109;109;110;110;112;117;111;111;109 04:29:17 INFO - PROCESS | 2260 | |1;audio-beat-detection;157;132;148;170;118;142;140;120;171;117 04:29:17 INFO - PROCESS | 2260 | |2;audio-dft;184;184;189;184;184;187;181;185;182;184 04:29:17 INFO - PROCESS | 2260 | |3;audio-fft;78;71;88;93;57;57;58;76;85;54 04:29:17 INFO - PROCESS | 2260 | |4;audio-oscillator;91;97;135;77;76;78;126;79;80;127 04:29:17 INFO - PROCESS | 2260 | |5;imaging-gaussian-blur;87;88;86;72;132;90;87;84;86;84 04:29:17 INFO - PROCESS | 2260 | |6;imaging-darkroom;92;118;150;100;97;93;93;93;92;93 04:29:17 INFO - PROCESS | 2260 | |7;imaging-desaturate;131;141;135;84;83;83;85;85;84;83 04:29:17 INFO - PROCESS | 2260 | |8;json-parse-financial;60;62;60;58;58;58;57;77;63;87 04:29:17 INFO - PROCESS | 2260 | |9;json-stringify-tinderbox;64;63;64;79;78;73;65;68;69;69 04:29:17 INFO - PROCESS | 2260 | |10;stanford-crypto-aes;82;83;84;113;108;107;101;81;83;85 04:29:17 INFO - PROCESS | 2260 | |11;stanford-crypto-ccm;140;152;161;133;134;134;136;134;181;153 04:29:17 INFO - PROCESS | 2260 | |12;stanford-crypto-pbkdf2;178;156;206;226;216;170;165;169;170;168 04:29:17 INFO - PROCESS | 2260 | |13;stanford-crypto-sha256-iterative;70;68;67;68;71;67;67;70;69;68 04:29:17 INFO - PROCESS | 2260 | __end_tp_report 04:29:17 INFO - PROCESS | 2260 | __start_cc_report 04:29:17 INFO - PROCESS | 2260 | _x_x_mozilla_cycle_collect,1931 04:29:17 INFO - PROCESS | 2260 | __end_cc_report 04:29:17 INFO - PROCESS | 2260 | __startTimestamp1461324557243__endTimestamp 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | ------- Summary: start ------- 04:29:17 INFO - PROCESS | 2260 | Number of tests: 14 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#0] ai-astar Cycles:10 Average:110.80 Median:110.50 stddev:2.39 (2.2%) stddev-sans-first:2.52 04:29:17 INFO - PROCESS | 2260 | Values: 110.0 109.0 109.0 110.0 110.0 112.0 117.0 111.0 111.0 109.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:145.00 stddev:20.20 (13.9%) stddev-sans-first:20.63 04:29:17 INFO - PROCESS | 2260 | Values: 157.0 132.0 148.0 170.0 118.0 142.0 140.0 120.0 171.0 117.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#2] audio-dft Cycles:10 Average:184.40 Median:184.00 stddev:2.27 (1.2%) stddev-sans-first:2.40 04:29:17 INFO - PROCESS | 2260 | Values: 184.0 184.0 189.0 184.0 184.0 187.0 181.0 185.0 182.0 184.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#3] audio-fft Cycles:10 Average:71.70 Median:77.00 stddev:14.48 (18.8%) stddev-sans-first:15.18 04:29:17 INFO - PROCESS | 2260 | Values: 78.0 71.0 88.0 93.0 57.0 57.0 58.0 76.0 85.0 54.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:78.50 stddev:23.65 (30.1%) stddev-sans-first:25.00 04:29:17 INFO - PROCESS | 2260 | Values: 91.0 97.0 135.0 77.0 76.0 78.0 126.0 79.0 80.0 127.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:86.50 stddev:15.68 (18.1%) stddev-sans-first:16.60 04:29:17 INFO - PROCESS | 2260 | Values: 87.0 88.0 86.0 72.0 132.0 90.0 87.0 84.0 86.0 84.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:93.00 stddev:18.60 (20.0%) stddev-sans-first:19.36 04:29:17 INFO - PROCESS | 2260 | Values: 92.0 118.0 150.0 100.0 97.0 93.0 93.0 93.0 92.0 93.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#7] imaging-desaturate Cycles:10 Average:99.40 Median:83.50 stddev:25.15 (30.1%) stddev-sans-first:23.93 04:29:17 INFO - PROCESS | 2260 | Values: 131.0 141.0 135.0 84.0 83.0 83.0 85.0 85.0 84.0 83.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:61.00 stddev:9.96 (16.3%) stddev-sans-first:10.45 04:29:17 INFO - PROCESS | 2260 | Values: 60.0 62.0 60.0 58.0 58.0 58.0 57.0 77.0 63.0 87.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:5.77 (8.4%) stddev-sans-first:5.80 04:29:17 INFO - PROCESS | 2260 | Values: 64.0 63.0 64.0 79.0 78.0 73.0 65.0 68.0 69.0 69.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:82.50 stddev:12.88 (15.6%) stddev-sans-first:13.07 04:29:17 INFO - PROCESS | 2260 | Values: 82.0 83.0 84.0 113.0 108.0 107.0 101.0 81.0 83.0 85.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:146.00 stddev:15.89 (10.9%) stddev-sans-first:16.71 04:29:17 INFO - PROCESS | 2260 | Values: 140.0 152.0 161.0 133.0 134.0 134.0 136.0 134.0 181.0 153.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.40 Median:174.00 stddev:24.27 (13.9%) stddev-sans-first:25.69 04:29:17 INFO - PROCESS | 2260 | Values: 178.0 156.0 206.0 226.0 216.0 170.0 165.0 169.0 170.0 168.0 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - PROCESS | 2260 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:1.43 (2.1%) stddev-sans-first:1.41 04:29:17 INFO - PROCESS | 2260 | Values: 70.0 68.0 67.0 68.0 71.0 67.0 67.0 70.0 69.0 68.0 04:29:17 INFO - PROCESS | 2260 | -------- Summary: end -------- 04:29:17 INFO - PROCESS | 2260 | 04:29:17 INFO - TEST-INFO | 2260: exit 0 04:29:18 INFO - TEST-OK | kraken | took 59201ms 04:29:18 INFO - SUITE-END | took 1175s 04:29:18 INFO - Completed test suite (00:19:36) 04:29:18 INFO - javascript benchmark 04:29:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3369.8315084245787, 3419.5725534308212, 3445.2592692571093, 3457.827108644567, 3469.2367679110603, 5624.859378515539, 5671.517920976717, 5742.253507044084, 5746.741396637156, 5747.712614369275, 5681.403452637465, 5749.396313387097, 5758.3665796762425, 5762.827115186541, 5762.942370576293, 4728.71627702339, 4760.8095676173, 5167.7932882684645, 5172.793088276464, 5248.632595718302, 5247.291615631898, 5252.868678283055, 5253.500917412859, 5254.553362964152, 5259.342582177228, 5181.818636347729, 5212.400573934008, 5240.109181439165, 5250.973745131269, 5318.893622127555, 5246.317978662769, 5256.658317209369, 5271.65734227274, 5279.762410691528, 5319.202119682079, 5103.515166059218, 5139.357580302462, 5157.6647517911215, 5193.039287731744, 5194.35070616173, 5642.661440313554, 5663.631863928831, 5690.9146362804595, 5693.117566777156, 5749.137629355589, 6228.781992630282, 6270.65511396873, 6273.62358258506, 6276.3409841966395, 6281.057841323793, 6026.819195424145, 6128.264608246995, 6189.845253868644, 6316.0, 6317.6209427434505, 5772.826815195551, 5790.739416726257, 5802.1296805479515, 5817.825465236049, 5819.67991760453, 6278.30938596754, 6295.874082518324, 6298.779542715983, 6321.715522801485, 6324.557280990287, 6070.150178974947, 6098.176746139303, 6109.663968481687, 6133.82435864808, 6142.109394137825, 6251.749930002749, 6311.179546658905, 6320.4311611955145, 6325.936740632535, 6353.174087368612, 6189.783357582463, 6236.314005459396, 6249.625022498664, 6272.3100458949475, 6275.6548389839, 6134.601250918676, 6184.5052395808225, 6185.876282474415, 6242.812715618538, 6300.873982520416, 4918.1884988977135, 4947.010597880367, 4951.554360107608, 4960.70235785854, 4972.428170760337, 4464.598186163261, 4500.819967201275, 4506.2564676828615, 4513.81944722214, 4525.366448697185, 5699.829005129853, 5722.656640601494, 5728.942710572925, 5736.77052917887, 5789.971050144806, 6246.531510136758, 6291.433770960635, 6310.0, 6312.810615681537, 6348.492120630339, 5743.511801496836, 5763.394843541451, 5771.538276937835, 5790.073588225863, 5829.912551311733, 5336.279602253724, 5407.351117865881, 5434.456554344534, 5437.075697131497, 5444.101723215705, 6337.271213810379, 6387.648679322496, 6390.392912673288, 6404.519661025349, 6404.871902561923, 6287.528435367457, 6316.210473690789, 6323.905141422974, 6332.873342533216, 6344.587601805868, 5363.839084827461, 6207.6585787782105, 6364.236291645032, 6367.42693157618, 6379.043143528508, 6229.844253893689, 6233.127362169391, 6246.312905580474, 6253.09330147117, 6259.906101408574, 6135.509159267337, 6259.530535209787, 6280.874382512416, 6301.149344838388, 6327.525435592257, 6005.189299444519, 6070.817875463743, 6104.786332478342, 6120.233815636221, 6147.170132032118, 6110.229009153497, 6136.864680034208, 6141.486007784186, 6148.9692551536955, 6157.89157951573, 5963.314218864886, 5986.491148252273, 5991.580589358878, 6020.6989650516425, 6034.668093254913, 5960.195373624679, 6093.9085913712215, 6121.938780612137, 6160.661163636043, 6166.691665416622, 6037.69811509414, 6052.425019623129, 6054.091886217103, 6071.3625069368845, 6081.847953801191], "value": 5727.920145087672, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.6335248563577, 511.9734931462417, 513.4198355857881, 525.5270256768907, 577.7602295047554, 29818.0, 30453.390932181344, 30556.84721576389, 37590.0602484938, 38954.220915581674, 41326.17347653045, 42165.15669686604, 42193.7890310548, 42238.0, 42251.0, 9474.431534107942, 9663.178629816473, 9674.854877176847, 9682.12860842524, 9687.418754874694, 8555.230029297361, 8835.469871807712, 9490.715278541655, 11483.311001339904, 11854.933056024956, 11486.310821350704, 11608.59369922053, 11609.361485118316, 11615.941920290386, 11620.0122989546, 8531.232189102948, 11438.0, 11546.018588419995, 11556.422178891022, 11583.710407239845, 8661.480311181349, 8776.692815751418, 8784.253338466238, 11308.886911130865, 11714.472848721827, 30393.24016899595, 38833.22333553341, 38917.83246502609, 38935.41596876049, 38976.61023389758, 2948.3661012882335, 3283.0315057058224, 3371.96628033719, 3766.453864189677, 3869.7484663497066, 24192.637110443357, 26068.34829129287, 28288.868445262164, 28294.717052829415, 28318.575221371695, 2645.378336091017, 2680.1691475642615, 2826.095649392175, 2829.4199689063694, 2835.6313679221776, 425291.8735406334, 437955.43066854344, 438484.4227336593, 441326.0, 442731.0, 60964.0, 61493.38506614966, 61779.0, 61925.38074619286, 62060.37939620546, 64721.352786472475, 65178.674106629165, 65246.0, 65288.347116528224, 65329.0, 16467.917660411862, 17211.0, 17239.655206895794, 17247.396341128002, 17272.05003724782, 11949.701257468632, 12265.0, 13173.802392964113, 13243.536476223286, 13259.73480530384, 16396.0, 16567.0, 16698.24857881374, 18881.81118188828, 19539.316123935594, 6426.786606696652, 6533.346665333524, 6837.940119281519, 6963.7562685305775, 7730.845383092306, 982.6904525074578, 1004.457592899826, 1017.2777328097134, 1024.7950409918046, 1065.9093977011885, 23921.641175382385, 29380.0, 29482.968096116532, 29491.0, 29794.70205297919, 1092.0771947704336, 1106.4523061084653, 1129.373197875187, 1146.4382452598168, 1214.40494157862, 15047.473338433321, 15262.923685381724, 15263.847361526465, 15674.216289185495, 15757.369705211893, 637675.0, 638028.0, 645775.0, 646938.0, 651947.0, 59819.40180598138, 60314.69842650759, 60503.09245361235, 60591.39408605946, 60641.0, 1427.772115980256, 1463.5024091808837, 1474.2555009720022, 1477.4976507987121, 1478.36430334957, 24626.50746985086, 28629.0, 28802.567961481014, 28817.559122044233, 29488.557671634513, 50204.24693629672, 50260.74869625628, 50748.238776417646, 50944.98110037853, 51027.0, 570668.0, 578328.0, 578790.0, 580137.0, 580367.0, 21582.784172158077, 23290.951907164555, 23844.403889902893, 26183.0, 26641.0, 31623.57693903826, 42026.57973420226, 45496.31755523603, 48023.279650804565, 48525.757371212916, 32142.874999374908, 34505.526338133306, 34616.13459663529, 36969.44545831868, 36986.26027479489, 34741.826290869394, 35200.82399587986, 35222.76720314776, 35940.0, 36059.81970090133, 35230.0, 35548.11129721778, 36029.63970360263, 36040.098997525274, 36162.0, 31834.363312733152, 31970.52044219292, 32548.51177232296, 32578.185545361557, 32669.836650816593, 21428.250011249533, 21563.784362156177, 24308.0, 24454.87772561126, 24759.87620061888], "value": 19600.426821907116, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.707141358327, 387.5330227076373, 400.6714494114826, 406.85556627397256, 411.30900087852393, 13932.651683707887, 14909.701805963874, 19473.3184338548, 20447.591048179027, 20449.488762780937, 16076.919615401936, 18327.54181145472, 20083.59832803343, 20547.486312842186, 20936.057877395517, 5677.318721753385, 5867.3252575953675, 5867.325257595388, 5870.647761134345, 5872.500837428803, 5830.0, 5866.266716660418, 5869.85325366865, 7307.561546307212, 7819.843603127934, 4662.0, 4956.3804524434445, 7428.368588669942, 7802.33680137189, 7855.41084418668, 7697.5381477111, 7782.727604533842, 7801.141874393812, 7808.219178082203, 7830.921690783076, 7262.382697470722, 7346.37555807757, 7568.0, 7601.885971710429, 7616.200298968584, 26664.20007399781, 26683.866580667167, 27046.72953270462, 27107.45785084287, 27808.86095569509, 2148.6669566217106, 2181.9563608727735, 2234.0, 2287.7254729432575, 3039.37692772981, 12684.492620295177, 12811.103222774409, 15608.531744047697, 16012.83987160125, 16455.177241137895, 1850.241401025573, 1852.7869295030973, 1870.1584287070737, 1873.681474149398, 1950.619629172312, 122364.0, 127700.7229927698, 129713.0, 130810.3459482706, 134701.32649336787, 20075.29736459217, 20655.69016464754, 20732.0, 20839.374818755463, 20844.47888802792, 35741.28517429637, 35817.64182358195, 35851.10372240715, 35863.641363586554, 35901.28197436037, 10612.734681633021, 10881.564737410416, 10923.726906827234, 11075.11399088071, 11286.98417142461, 8957.462552246887, 8969.013408525057, 9023.368364214573, 9079.001309856043, 9130.086991300817, 9289.581968811419, 9469.621215151454, 9549.71078904339, 9553.856692149624, 9558.0, 3028.9394212115635, 3584.7849129052343, 3711.498947642088, 3786.497489821904, 3796.468494410785, 920.7191806499083, 937.8358787212211, 970.7233438470143, 972.4651441707033, 1056.9947150264354, 17993.0, 19286.71069933952, 19342.0, 19429.708554371697, 19706.507337316394, 942.1661829280998, 972.2902281334665, 1008.7780688248573, 1011.8988101189822, 1083.1389045708681, 9843.212542996544, 10475.1096156826, 10541.367517948947, 10564.524596393181, 10565.408217981078, 570221.0, 584526.0, 585318.0, 597993.0, 599189.0, 97360.53959190618, 123081.0, 126654.36672816762, 127111.0, 132397.70409183524, 837.351052933981, 1020.7295066807302, 1044.7335929337971, 1045.0855501436242, 1063.4948399510172, 17025.233864475882, 17456.301747930447, 17688.55778605545, 17755.911220443813, 17840.821591783915, 51238.74380628073, 52138.73930630323, 53544.73227633837, 54086.45913540814, 55257.0, 68047.65976170088, 78873.0, 80634.0, 81325.0, 82902.75645865437, 7847.5089732950555, 8001.359891208573, 8153.0, 8159.26566609008, 8403.95798021006, 10306.381617102998, 11861.229020113666, 12608.621741347773, 12674.302913339487, 12800.295983720613, 10247.692569222936, 10661.360318380923, 10966.72740433804, 10990.631117043682, 11179.329240245612, 10391.636292729718, 10556.630517931835, 10732.73168170802, 10986.285891417032, 11028.0, 11190.32858028521, 11216.214864959375, 11331.150163737917, 11404.973552380327, 11547.364894930859, 7881.330086942674, 8051.758447246592, 8157.1842815717955, 8240.464369815943, 8392.748217553482, 8153.429259951984, 10078.143357814668, 10810.729731756768, 11326.773464530499, 11375.20373573842], "value": 10725.340015219459, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.872807083327, 1380.4961189165945, 1567.2320562924172, 1754.8508376787972, 1968.045497933503, 7854.0, 8590.226879580836, 8838.292936565083, 8856.645734170625, 8857.424267422613, 8416.747497575067, 8650.307975361971, 8661.133886611336, 8672.956635216831, 8677.783055423617, 2098.3075585056895, 2383.7139543254834, 2415.625578035407, 2457.066314800373, 2476.0838489758726, 2447.7552244775557, 2469.8641574713383, 2471.5921872890954, 2476.1209770531473, 2479.417336925821, 2406.3142004528627, 2425.490646964131, 2430.6597076409316, 2435.3424575364643, 2436.719777225624, 2113.5033267182125, 2335.065973610552, 2400.1719406804623, 2421.0315873650507, 2421.5278020785954, 2387.164492427654, 2420.3586049696846, 2422.0917156066475, 2422.7213870404953, 2423.248792874197, 8591.26974207193, 8652.78368040804, 8658.78353041179, 8662.91713535808, 8672.739817805414, 5046.899062018776, 5188.766505507224, 5244.344456942882, 5289.682619042831, 5310.70791106489, 3440.9655903440894, 3533.063738109403, 3542.8937131886087, 3578.3737845876867, 3600.4419315006216, 1012.3622118065645, 1024.9795004099876, 1029.7837454134565, 1033.8500917367014, 1046.947652617366, 9283.814323713555, 11503.07975361969, 11675.824862627067, 11690.123240756891, 11741.4716337765, 4442.067165895133, 4537.319402089713, 4685.320628508847, 4693.24908014716, 4703.741294228782, 9177.587008584629, 9183.40307879999, 9194.0, 9196.862047069299, 9205.309601779893, 5243.580513558983, 5251.76367063483, 5253.632245742761, 5276.498732620395, 5282.947170528246, 4378.452693413324, 4549.545045495424, 4700.153972285021, 4855.902881942412, 4861.927071093936, 3496.4056110461283, 3733.0, 3844.2888065707934, 3849.345611246095, 3856.633619806114, 1209.5464200924653, 1217.896478799312, 1217.9634610961684, 1226.405193481161, 1228.4963165102265, 835.3943391041568, 837.794740288621, 872.9524570515407, 919.3426699909596, 975.1516180922642, 5682.516986056149, 5708.80019199326, 5896.351401345849, 6030.556816424639, 6065.484433823086, 929.4934260827865, 965.7006328038331, 975.0152346130459, 990.2226751999646, 1016.7254841192985, 3858.9421158682644, 3949.1311911379453, 3990.4014397840556, 4024.0, 4024.1348110155873, 9655.903440965641, 10288.691339259834, 10599.470026498799, 10907.781844363068, 11098.223124381217, 10876.72808179786, 10995.065419439277, 11026.44867756609, 11055.834162487568, 11065.50205240766, 8130.674773009014, 8167.959160204161, 8195.098539160688, 8270.627821748036, 8273.22125742956, 5797.9420205797405, 5809.302883653989, 5864.296284445893, 6028.2766068072115, 6104.633721976696, 9694.224462043161, 9746.463944483121, 9869.605215791287, 9939.105480506789, 10037.949810250902, 10068.949655251678, 10085.445300508543, 10125.341852779546, 10218.437985910548, 10274.33216840903, 6633.433927140723, 6902.965485172743, 7157.0, 7360.852782944204, 7538.6607602659105, 7813.531188128731, 7905.130435652189, 7925.484843485155, 8431.915680843113, 8436.11420800807, 7341.3392794648735, 7452.217517160837, 7479.439042071757, 7605.6577454013595, 7690.192529784292, 6952.096227490394, 7259.346658800733, 7297.817554561178, 7400.740974065882, 7419.629018549159, 6907.20567134786, 7101.502894797314, 7135.357817796215, 7145.249748776304, 7160.820979475555, 4804.591609713074, 4861.246506791453, 4878.487758785276, 4908.852734417973, 5130.999455106367, 4915.729634869974, 4969.975150124226, 4982.4768399317545, 5024.748762561784, 5186.611004174777], "value": 4558.7881300883255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.0647276135588, 330.54054863739407, 334.77068208277325, 339.44670187594215, 343.1780884780948, 5952.166696662465, 6009.218801555797, 6091.451769340759, 6092.756289748405, 6135.417135372144, 5612.831615051545, 5944.464998150165, 6016.8495787605225, 6031.471589120359, 6047.5766696331275, 3905.9023524411973, 4267.295896177135, 4375.453068366454, 4389.319655453394, 4400.647948164155, 4380.408644832954, 4451.866444006685, 4477.776111194427, 4493.168763778712, 4506.5493450655, 4446.2219111655495, 4469.82120715171, 4475.0, 4484.349769283452, 4490.979059423256, 3665.2669466106886, 4263.104748002923, 4375.453068366454, 4467.977660111711, 4477.485174138285, 3206.502992036238, 3517.120719820045, 3547.0, 3872.380419132925, 3876.3022655921923, 5402.567794576425, 5692.0, 5696.401877802812, 5720.2849643794525, 5722.938228047974, 5083.847484575469, 5218.225803226883, 5224.216367544898, 5233.633645644806, 5269.920951185735, 5275.498827611371, 5551.278333816618, 6357.777477788301, 6401.327860574619, 6406.711697973602, 4215.578442155791, 4216.7259128156575, 4334.154839806239, 4352.673549483801, 4359.215341238576, 5030.622703297267, 6393.328700486429, 6602.768903088417, 6689.8662026759675, 6724.293949135319, 4364.127174565099, 4364.6944713869725, 4368.541303163122, 4380.824767009284, 4410.42664453619, 3770.321342158438, 4487.528809475023, 4489.775511224426, 4492.505824359318, 4495.865124046284, 4585.0, 5036.244563315532, 5209.895802083937, 5222.4255331913455, 5261.394939581921, 6405.455536279375, 6422.325655806167, 6432.614043157426, 6440.13058237132, 6441.194850643669, 1473.3001824133664, 1522.6802371501888, 1543.3672194400242, 1543.4597890738153, 1547.5744170353244, 864.8400045991386, 1005.7234260578399, 1022.8823685276288, 1027.4554486122368, 1031.5254982707888, 5967.582269241198, 5987.353477793642, 6007.158997740233, 6022.759089636453, 6054.3945605439985, 6355.697082098159, 6608.207015158116, 6647.734090636417, 6650.86698266032, 6655.73377064912, 5277.498637629419, 5476.123820188751, 5842.954965761949, 6050.6672133032225, 6093.207882975274, 3418.5555877735737, 3467.4625433057918, 3469.6344545790544, 3473.4616134499192, 3499.825008749552, 5947.226860508193, 6525.3474652535315, 6889.862202755917, 6890.276520965226, 6921.515493915377, 5454.836354909359, 6204.81385558434, 6251.374862513712, 6795.3204679531655, 6879.346462086094, 6132.6320420774, 6822.33928767781, 6875.862482750417, 6911.377975982085, 6911.75808846688, 5745.0, 5824.621399609012, 6046.939530604637, 6594.208694956636, 6595.967020164869, 5212.296339994204, 5621.763212093333, 5806.970965145148, 6217.0, 6510.804675859732, 5948.73230704628, 6466.062420948842, 6824.385805277549, 6941.618210998444, 6952.374286314256, 3235.822029788384, 3600.3159399713973, 4278.850240241577, 4288.31386978082, 4290.141971605629, 6348.682565871595, 6648.6010839347855, 6670.232923213893, 6689.2307384651385, 6725.562838415487, 6419.711112999833, 6438.67806609677, 6459.095726598186, 6466.935330646634, 6473.320301368289, 5402.594805389533, 5501.927124210741, 5561.332640083216, 6167.321594624677, 6358.263093995003, 5058.974705126451, 6248.4688801452385, 6254.155688981929, 6291.465225455888, 6338.968305158444, 6071.908921366271, 6159.599626024294, 6465.191851018622, 6465.773697920738, 6477.222733272038, 5206.427292997691, 6310.968445157745, 6323.936760632335, 6359.908701797555, 6366.267879193952], "value": 4620.752212166657, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.4276529740584, 668.6523008035822, 716.7455553278586, 743.5241445474894, 763.9312461878429, 14741.631459213526, 14960.401583936644, 14963.027403218783, 14987.625309367271, 15038.172900490468, 13571.52499662512, 13835.446582136728, 13911.582652520441, 14250.0, 14297.642558936031, 925.8055808280269, 926.9953650231739, 946.0066929723797, 947.554649314821, 947.9383840050409, 727.396261103283, 738.6048464071723, 898.9505577193252, 932.7807965128189, 939.5255396024997, 984.4585477987114, 986.270160081538, 986.4377304936189, 988.4810474500908, 989.7426669066059, 755.5768769489049, 981.087588542655, 981.2787601113176, 981.4994352880046, 982.40564458503, 707.1584814071239, 730.0800990751691, 732.1251104929611, 732.3372348025046, 735.400648471494, 15312.923435382752, 15328.463503777426, 15344.846551534454, 15435.30541125652, 15933.123678197695, 10782.299150555267, 12202.572909948109, 12972.0, 12989.285589292585, 13349.599512014654, 9769.609215631366, 10036.749081272954, 10168.847467287997, 10593.470326483644, 10821.621243256526, 3587.515685382466, 3657.5976642569294, 3687.870924517629, 3779.3764028935193, 3800.7529510581726, 76009.09977250558, 76924.0, 77304.84042739363, 78330.65008049768, 83526.58236708777, 9185.678501252423, 9290.44257344548, 9436.858447123299, 9628.18160456369, 9787.021297870157, 21107.472313192295, 21155.78844211569, 21167.0, 21184.881511848693, 21218.575628487342, 6533.673316334164, 7352.669129889167, 7418.035655364768, 7452.962735186289, 7484.47608667399, 3935.2916475034767, 4034.717569770147, 4045.696572757055, 4052.230076285496, 4249.575042495726, 3606.314800187956, 3696.667299943018, 3700.833462494194, 3702.148505843671, 3704.6110158433517, 559.3846768554639, 629.0846817879974, 647.0326861342323, 649.6719156825773, 653.8038588423454, 522.5949888836183, 583.3379114704841, 601.7983975368213, 610.4505944649762, 634.403660559073, 7845.1762564931005, 7970.760877173694, 7981.0, 8071.717489887826, 8239.423240373117, 568.8919105369966, 584.1352817710142, 597.8624916269282, 609.5550248318752, 613.3069631316583, 3644.2711457708565, 3769.830357633913, 3780.0360907968293, 3879.903002424962, 3917.490726205575, 87515.0, 89458.0, 89943.55028224816, 90153.0, 90233.54883225542, 74228.0, 74654.62672686602, 75867.0, 77108.61445692736, 78235.60882195666, 65042.34957650457, 65106.88503609499, 65228.78169746409, 65516.0, 67166.32833671698, 7380.483366164317, 7457.366123879531, 7503.812404689708, 7698.576578288248, 7848.60756962161, 45075.774621126686, 45249.0, 45277.547224527334, 45368.0, 46074.0, 63276.78531251384, 64858.0, 64861.67569162124, 65113.37216569624, 65493.672531637036, 10015.39907605546, 10058.8491172631, 10076.24428167905, 10254.897451025692, 10392.58429662805, 9651.324407291422, 10771.784564308826, 10855.620053298097, 11192.888071119185, 11339.943300283447, 10217.846732299171, 10233.846492302473, 10324.587016519255, 10384.32501887375, 10631.362118272928, 8874.378793484395, 10168.542415591168, 10184.0, 10311.123554497652, 10323.432211228455, 9305.860412093689, 9675.564599592895, 10001.599936002478, 10084.899151008396, 10485.9475702621, 7622.237776222333, 8274.58627068632, 8616.698415555426, 8803.0, 8858.066669655798, 8666.826663466822, 8776.078511756174, 8854.557272136497, 8922.643094276156, 9026.142516460926], "value": 6702.100979909502, "unit": "score"}], "name": "dromaeo_css", "value": 7443.002601049657, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 109.0, 110.0, 110.0, 112.0, 117.0, 111.0, 111.0, 109.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 132.0, 148.0, 170.0, 118.0, 142.0, 140.0, 120.0, 171.0, 117.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 189.0, 184.0, 184.0, 187.0, 181.0, 185.0, 182.0, 184.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 71.0, 88.0, 93.0, 57.0, 57.0, 58.0, 76.0, 85.0, 54.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 135.0, 77.0, 76.0, 78.0, 126.0, 79.0, 80.0, 127.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 72.0, 132.0, 90.0, 87.0, 84.0, 86.0, 84.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 118.0, 150.0, 100.0, 97.0, 93.0, 93.0, 93.0, 92.0, 93.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 141.0, 135.0, 84.0, 83.0, 83.0, 85.0, 85.0, 84.0, 83.0], "value": 99.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 58.0, 58.0, 58.0, 57.0, 77.0, 63.0, 87.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 79.0, 78.0, 73.0, 65.0, 68.0, 69.0, 69.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 84.0, 113.0, 108.0, 107.0, 101.0, 81.0, 83.0, 85.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 152.0, 161.0, 133.0, 134.0, 134.0, 136.0, 134.0, 181.0, 153.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 156.0, 206.0, 226.0, 216.0, 170.0, 165.0, 169.0, 170.0, 168.0], "value": 182.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 68.0, 71.0, 67.0, 67.0, 70.0, 69.0, 68.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1518.7, "alertThreshold": 2.0}]} 04:29:18 INFO - Return code: 0 04:29:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:29:18 ERROR - # TBPL SUCCESS # 04:29:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:29:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:29:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:29:18 INFO - 2016-04-22 04:29:18.575 screenresolution[2264:25627] starting screenresolution argv=screenresolution get 04:29:18 INFO - 2016-04-22 04:29:18.577 screenresolution[2264:25627] Display 0: 1600x1200x32@60 04:29:18 INFO - 2016-04-22 04:29:18.583 screenresolution[2265:25631] starting screenresolution argv=screenresolution list 04:29:18 INFO - Available Modes on Display 0 04:29:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:29:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:29:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:29:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:29:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:29:18 INFO - Intel Iris: 04:29:18 INFO - Chipset Model: Intel Iris 04:29:18 INFO - Type: GPU 04:29:18 INFO - Bus: Built-In 04:29:18 INFO - VRAM (Dynamic, Max): 1536 MB 04:29:18 INFO - Vendor: Intel (0x8086) 04:29:18 INFO - Device ID: 0x0a2e 04:29:18 INFO - Revision ID: 0x0009 04:29:18 INFO - Displays: 04:29:18 INFO - Display: 04:29:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:29:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:29:18 INFO - Display Serial Number: 1600x1200 60 04:29:18 INFO - Main Display: Yes 04:29:18 INFO - Mirror: Off 04:29:18 INFO - Online: Yes 04:29:18 INFO - Rotation: Supported 04:29:18 INFO - Return code: 0 04:29:18 INFO - Running post-action listener: _resource_record_post_action 04:29:18 INFO - Running post-run listener: _resource_record_post_run 04:29:19 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 0; Write bytes: 697596928; Read time: 0; Write time: 2169 04:29:19 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 388190720; Write bytes: 382611968; Read time: 28428; Write time: 685 04:29:19 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 0; Write bytes: 295211520; Read time: 0; Write time: 1375 04:29:19 INFO - Running post-run listener: _upload_blobber_files 04:29:19 INFO - Blob upload gear active. 04:29:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:29:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:29:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:29:20 INFO - (blobuploader) - INFO - Open directory for files ... 04:29:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:29:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:29:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:21 INFO - (blobuploader) - INFO - Done attempting. 04:29:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:29:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:29:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:22 INFO - (blobuploader) - INFO - Done attempting. 04:29:22 INFO - (blobuploader) - INFO - Iteration through files over. 04:29:22 INFO - Return code: 0 04:29:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:29:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:29:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01c0a88c6fd66c0fecd6c574e176b10515e0e3336ddac65e6b9cacbd64a7c6d5d2de605863e82e21faf568f344273e6460772828c5564e015158c87cfa972d39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/094b36e3c2170e4ab913c6c9f1a85df56343f5e498ee4e175e1b1006a972d3d390de43e286a0485760939825c1e86b41c3a65f5b562d4e239a4eab03b5ec58c5"} 04:29:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:29:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:29:22 INFO - Contents: 04:29:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01c0a88c6fd66c0fecd6c574e176b10515e0e3336ddac65e6b9cacbd64a7c6d5d2de605863e82e21faf568f344273e6460772828c5564e015158c87cfa972d39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/094b36e3c2170e4ab913c6c9f1a85df56343f5e498ee4e175e1b1006a972d3d390de43e286a0485760939825c1e86b41c3a65f5b562d4e239a4eab03b5ec58c5"} 04:29:22 INFO - Running post-run listener: copy_logs_to_upload_dir 04:29:22 INFO - Copying logs to upload dir... 04:29:22 INFO - mkdir: /builds/slave/test/build/upload/logs 04:29:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.767651 ========= master_lag: 22.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-04-22 04:29:44.596070) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2016-04-22 04:29:44.599092) ========= 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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01c0a88c6fd66c0fecd6c574e176b10515e0e3336ddac65e6b9cacbd64a7c6d5d2de605863e82e21faf568f344273e6460772828c5564e015158c87cfa972d39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/094b36e3c2170e4ab913c6c9f1a85df56343f5e498ee4e175e1b1006a972d3d390de43e286a0485760939825c1e86b41c3a65f5b562d4e239a4eab03b5ec58c5"} build_url:https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/ZQsQA1iESTOLKHMCHpBvZQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01c0a88c6fd66c0fecd6c574e176b10515e0e3336ddac65e6b9cacbd64a7c6d5d2de605863e82e21faf568f344273e6460772828c5564e015158c87cfa972d39", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/094b36e3c2170e4ab913c6c9f1a85df56343f5e498ee4e175e1b1006a972d3d390de43e286a0485760939825c1e86b41c3a65f5b562d4e239a4eab03b5ec58c5"}' ========= master_lag: 6.63 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 6 secs) (at 2016-04-22 04:29:51.236350) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-22 04:29:51.246691) ========= 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.FL1Ufu35e0/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.d5oSEeQ3rf/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.005126 ========= master_lag: 3.85 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-22 04:29:55.106358) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-22 04:29:55.106641) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-22 04:29:56.146632) ========= ========= Total master_lag: 32.71 =========