builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0295 starttime: 1468236169.64 results: success (0) buildid: 20160711021108 builduid: 2d61cd1c1a984fb5939e87c71e00c98f revision: 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:49.648020) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:49.648470) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:49.648765) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:49.674766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:49.675178) ========= 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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-11 04:22:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.58M=0.001s 2016-07-11 04:22:49 (8.58 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080272 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:49.777040) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:49.777357) ========= 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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.018082 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:49.825835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 04:22:49.826158) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 --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 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bA8jGT8hIq/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.sxPpDCgZDh/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-11 04:22:49,900 truncating revision to first 12 chars 2016-07-11 04:22:49,900 Setting DEBUG logging. 2016-07-11 04:22:49,900 attempt 1/10 2016-07-11 04:22:49,900 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1a3c9b20d193?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 04:22:51,134 unpacking tar archive at: fx-team-1a3c9b20d193/testing/mozharness/ program finished with exit code 0 elapsedTime=1.510044 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 04:22:51.357427) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:51.357889) ========= script_repo_revision: 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:51.358321) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:51.358624) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 04:22:51.376912) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-11 04:22:51.377233) ========= /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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:22:51 INFO - MultiFileLogger online at 20160711 04:22:51 in /builds/slave/test 04:22:51 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:22:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:22:51 INFO - {'append_to_log': False, 04:22:51 INFO - 'base_work_dir': '/builds/slave/test', 04:22:51 INFO - 'blob_upload_branch': 'Fx-Team', 04:22:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:22:51 INFO - 'branch': 'Fx-Team', 04:22:51 INFO - 'buildbot_json_path': 'buildprops.json', 04:22:51 INFO - 'config_files': ('talos/mac_config.py',), 04:22:51 INFO - 'default_actions': ('clobber', 04:22:51 INFO - 'read-buildbot-config', 04:22:51 INFO - 'download-and-extract', 04:22:51 INFO - 'populate-webroot', 04:22:51 INFO - 'create-virtualenv', 04:22:51 INFO - 'install', 04:22:51 INFO - 'run-tests'), 04:22:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:22:51 INFO - 'download_minidump_stackwalk': True, 04:22:51 INFO - 'download_symbols': 'ondemand', 04:22:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:22:51 INFO - 'tooltool.py': '/tools/tooltool.py', 04:22:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:22:51 INFO - '/tools/misc-python/virtualenv.py')}, 04:22:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:22:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:22:51 INFO - 'installer_path': 'installer.exe', 04:22:51 INFO - 'log_level': 'info', 04:22:51 INFO - 'log_name': 'talos', 04:22:51 INFO - 'log_to_console': True, 04:22:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:22:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:22:51 INFO - 'opt_config_files': (), 04:22:51 INFO - 'pip_index': False, 04:22:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:22:51 INFO - 'cmd': ('bash', 04:22:51 INFO - '-c', 04:22:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:22:51 INFO - 'enabled': True, 04:22:51 INFO - 'halt_on_failure': False, 04:22:51 INFO - 'name': 'check_screen_resolution'},), 04:22:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:22:51 INFO - 'cmd': ('bash', 04:22:51 INFO - '-c', 04:22:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:22:51 INFO - 'enabled': True, 04:22:51 INFO - 'halt_on_failure': False, 04:22:51 INFO - 'name': 'check_screen_resolution'},), 04:22:51 INFO - 'run_cmd_checks_enabled': True, 04:22:51 INFO - 'sps_profile': False, 04:22:51 INFO - 'sps_profile_interval': 0, 04:22:51 INFO - 'suite': 'dromaeojs', 04:22:51 INFO - 'system_bits': '32', 04:22:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:22:51 INFO - 'title': 't-yosemite-r7-0295', 04:22:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:22:51 INFO - 'use_talos_json': True, 04:22:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:22:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:22:51 INFO - 'work_dir': 'build'} 04:22:51 INFO - ##### 04:22:51 INFO - ##### Running clobber step. 04:22:51 INFO - ##### 04:22:51 INFO - Running pre-action listener: _resource_record_pre_action 04:22:51 INFO - Running main action method: clobber 04:22:51 INFO - rmtree: /builds/slave/test/build 04:22:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:22:53 INFO - Running post-action listener: _resource_record_post_action 04:22:53 INFO - ##### 04:22:53 INFO - ##### Finished clobber step (success) 04:22:53 INFO - ##### 04:22:53 INFO - ##### 04:22:53 INFO - ##### Running read-buildbot-config step. 04:22:53 INFO - ##### 04:22:53 INFO - Running pre-action listener: _resource_record_pre_action 04:22:53 INFO - Running main action method: read_buildbot_config 04:22:53 INFO - Using buildbot properties: 04:22:53 INFO - { 04:22:53 INFO - "project": "", 04:22:53 INFO - "product": "firefox", 04:22:53 INFO - "script_repo_revision": "production", 04:22:53 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:22:53 INFO - "repository": "", 04:22:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:22:53 INFO - "buildid": "20160711021108", 04:22:53 INFO - "pgo_build": "False", 04:22:53 INFO - "basedir": "/builds/slave/test", 04:22:53 INFO - "buildnumber": 91, 04:22:53 INFO - "slavename": "t-yosemite-r7-0295", 04:22:53 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:22:53 INFO - "platform": "yosemite_r7", 04:22:53 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:22:53 INFO - "branch": "fx-team", 04:22:53 INFO - "repo_path": "integration/fx-team", 04:22:53 INFO - "revision": "1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9", 04:22:53 INFO - "stage_platform": "macosx64", 04:22:53 INFO - "builduid": "2d61cd1c1a984fb5939e87c71e00c98f", 04:22:53 INFO - "slavebuilddir": "test" 04:22:53 INFO - } 04:22:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:22:53 INFO - Running post-action listener: _resource_record_post_action 04:22:53 INFO - ##### 04:22:53 INFO - ##### Finished read-buildbot-config step (success) 04:22:53 INFO - ##### 04:22:53 INFO - ##### 04:22:53 INFO - ##### Running download-and-extract step. 04:22:53 INFO - ##### 04:22:53 INFO - Running pre-action listener: _resource_record_pre_action 04:22:53 INFO - Running main action method: download_and_extract 04:22:53 INFO - mkdir: /builds/slave/test/build/tests 04:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:22:53 INFO - https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:22:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:22:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:22:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:22:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 04:22:53 INFO - Downloaded 1199 bytes. 04:22:53 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:22:53 INFO - Using the following test package requirements: 04:22:53 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:22:53 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:22:53 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:22:53 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:22:53 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:22:53 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:22:53 INFO - u'jsshell-mac.zip'], 04:22:53 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:22:53 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:22:53 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:22:53 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:22:53 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:22:53 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:22:53 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:22:53 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:22:53 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:22:53 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:22:53 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:22:53 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:22:53 INFO - https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:22:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:22:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:22:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:22:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:22:55 INFO - Downloaded 18806922 bytes. 04:22:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:22:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:22:58 INFO - Return code: 0 04:22:58 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:22:58 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:22:58 INFO - https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:22:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:22:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:22:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:22:59 INFO - Downloaded 18806922 bytes. 04:22:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:22:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:23:03 INFO - Return code: 0 04:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:23:03 INFO - https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:23:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:23:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:23:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:23:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:23:04 INFO - Downloaded 14403333 bytes. 04:23:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:23:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:23:05 INFO - Return code: 0 04:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:23:05 INFO - https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:23:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:23:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:23:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 04:23:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 04:23:08 INFO - Downloaded 112359409 bytes. 04:23:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:23:08 INFO - mkdir: /builds/slave/test/properties 04:23:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:23:08 INFO - Writing to file /builds/slave/test/properties/build_url 04:23:08 INFO - Contents: 04:23:08 INFO - build_url:https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:23:10 INFO - Running post-action listener: _resource_record_post_action 04:23:10 INFO - Running post-action listener: set_extra_try_arguments 04:23:10 INFO - ##### 04:23:10 INFO - ##### Finished download-and-extract step (success) 04:23:10 INFO - ##### 04:23:10 INFO - ##### 04:23:10 INFO - ##### Running populate-webroot step. 04:23:10 INFO - ##### 04:23:10 INFO - Running pre-action listener: _resource_record_pre_action 04:23:10 INFO - Running main action method: populate_webroot 04:23:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:23:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:23:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:23:10 INFO - '--tppagecycles', 04:23:10 INFO - '7'], 04:23:10 INFO - 'tests': ['tsvgm']}}, 04:23:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:23:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:23:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:23:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:23:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:10 INFO - 'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:23:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:23:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:10 INFO - 'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['damp', 'tps']}, 04:23:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:10 INFO - 'tests': ['damp', 'tps']}, 04:23:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['dromaeo_dom']}, 04:23:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:23:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['basic_compositor_video']}, 04:23:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:23:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['a11yr', 04:23:10 INFO - 'ts_paint', 04:23:10 INFO - 'tpaint', 04:23:10 INFO - 'sessionrestore', 04:23:10 INFO - 'sessionrestore_no_auto_restore', 04:23:10 INFO - 'tabpaint']}, 04:23:10 INFO - 'other-e10s': {'tests': ['a11yr', 04:23:10 INFO - 'ts_paint', 04:23:10 INFO - 'tpaint', 04:23:10 INFO - 'sessionrestore', 04:23:10 INFO - 'sessionrestore_no_auto_restore', 04:23:10 INFO - 'tabpaint']}, 04:23:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:23:10 INFO - 'ts_paint', 04:23:10 INFO - 'tpaint', 04:23:10 INFO - 'sessionrestore', 04:23:10 INFO - 'sessionrestore_no_auto_restore', 04:23:10 INFO - 'tabpaint']}, 04:23:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:23:10 INFO - 'ts_paint', 04:23:10 INFO - 'tpaint', 04:23:10 INFO - 'sessionrestore', 04:23:10 INFO - 'sessionrestore_no_auto_restore', 04:23:10 INFO - 'tabpaint']}, 04:23:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['a11yr', 04:23:10 INFO - 'ts_paint', 04:23:10 INFO - 'tpaint', 04:23:10 INFO - 'sessionrestore', 04:23:10 INFO - 'sessionrestore_no_auto_restore', 04:23:10 INFO - 'tabpaint']}, 04:23:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['a11yr', 04:23:10 INFO - 'ts_paint', 04:23:10 INFO - 'tpaint', 04:23:10 INFO - 'sessionrestore', 04:23:10 INFO - 'sessionrestore_no_auto_restore', 04:23:10 INFO - 'tabpaint']}, 04:23:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['tsvgx', 04:23:10 INFO - 'tsvgr_opacity', 04:23:10 INFO - 'tart', 04:23:10 INFO - 'tscrollx', 04:23:10 INFO - 'cart']}, 04:23:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:23:10 INFO - 'tsvgr_opacity', 04:23:10 INFO - 'tart', 04:23:10 INFO - 'tscrollx', 04:23:10 INFO - 'cart']}, 04:23:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:10 INFO - 'talos_options': ['--disable-e10s'], 04:23:10 INFO - 'tests': ['tp5o']}, 04:23:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:10 INFO - 'tests': ['tp5o']}, 04:23:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:10 INFO - 'talos_options': ['--disable-e10s', 04:23:10 INFO - '--xperf_path', 04:23:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:23:10 INFO - 'tests': ['tp5n']}, 04:23:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:23:10 INFO - 'talos_options': ['--xperf_path', 04:23:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:23:10 INFO - 'tests': ['tp5n']}}, 04:23:10 INFO - 'talos.zip': {'path': '', 04:23:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:23:10 INFO - Running post-action listener: _resource_record_post_action 04:23:10 INFO - ##### 04:23:10 INFO - ##### Finished populate-webroot step (success) 04:23:10 INFO - ##### 04:23:10 INFO - ##### 04:23:10 INFO - ##### Running create-virtualenv step. 04:23:10 INFO - ##### 04:23:10 INFO - Running pre-action listener: _resource_record_pre_action 04:23:10 INFO - Running main action method: create_virtualenv 04:23:10 INFO - Creating virtualenv /builds/slave/test/build/venv 04:23:10 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:23:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:23:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:23:10 INFO - Using real prefix '/tools/python27' 04:23:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:23:10 INFO - Installing distribute.............................................................................................................................................................................................done. 04:23:13 INFO - Installing pip.................done. 04:23:13 INFO - Return code: 0 04:23:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:23:13 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:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:13 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:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:13 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 0x10f278a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f30d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6a84910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, '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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:23:13 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:23:13 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:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:13 INFO - 'HOME': '/Users/cltbld', 04:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:13 INFO - 'LOGNAME': 'cltbld', 04:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:13 INFO - 'MOZ_NO_REMOTE': '1', 04:23:13 INFO - 'NO_EM_RESTART': '1', 04:23:13 INFO - 'PAGER': '/bin/cat', 04:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:13 INFO - 'PWD': '/builds/slave/test', 04:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:13 INFO - 'SHELL': '/bin/bash', 04:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:23:13 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:13 INFO - 'USER': 'cltbld', 04:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:13 INFO - 'XPC_FLAGS': '0x0', 04:23:13 INFO - 'XPC_SERVICE_NAME': '0', 04:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:14 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:23:14 INFO - Cleaning up... 04:23:14 INFO - Return code: 0 04:23:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:23:14 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:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:14 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:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f278a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f30d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6a84910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, '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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:23:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:23:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:23:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:23:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:14 INFO - 'HOME': '/Users/cltbld', 04:23:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:14 INFO - 'LOGNAME': 'cltbld', 04:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:14 INFO - 'MOZ_NO_REMOTE': '1', 04:23:14 INFO - 'NO_EM_RESTART': '1', 04:23:14 INFO - 'PAGER': '/bin/cat', 04:23:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:14 INFO - 'PWD': '/builds/slave/test', 04:23:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:14 INFO - 'SHELL': '/bin/bash', 04:23:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:23:14 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:23:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:14 INFO - 'USER': 'cltbld', 04:23:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:14 INFO - 'XPC_FLAGS': '0x0', 04:23:14 INFO - 'XPC_SERVICE_NAME': '0', 04:23:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:14 INFO - Downloading/unpacking psutil>=3.1.1 04:23:14 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:23:14 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:23:14 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:23:14 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:23:14 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:23:14 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:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:23:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:23:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:23:17 INFO - Installing collected packages: psutil 04:23:17 INFO - Running setup.py install for psutil 04:23:17 INFO - building 'psutil._psutil_osx' extension 04:23:17 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:23:17 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:23:17 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:23:17 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:23:17 INFO - building 'psutil._psutil_posix' extension 04:23:17 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:23:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:23:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:23:17 INFO - ^ 04:23:17 INFO - 1 warning generated. 04:23:18 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:23:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:23:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:23:18 INFO - Successfully installed psutil 04:23:18 INFO - Cleaning up... 04:23:18 INFO - Return code: 0 04:23:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:23: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:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23: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:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f278a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f30d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6a84910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, '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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:23:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:23:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:23:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:18 INFO - 'HOME': '/Users/cltbld', 04:23:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:18 INFO - 'LOGNAME': 'cltbld', 04:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:18 INFO - 'MOZ_NO_REMOTE': '1', 04:23:18 INFO - 'NO_EM_RESTART': '1', 04:23:18 INFO - 'PAGER': '/bin/cat', 04:23:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:18 INFO - 'PWD': '/builds/slave/test', 04:23:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:18 INFO - 'SHELL': '/bin/bash', 04:23:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:23:18 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:23:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:18 INFO - 'USER': 'cltbld', 04:23:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:18 INFO - 'XPC_FLAGS': '0x0', 04:23:18 INFO - 'XPC_SERVICE_NAME': '0', 04:23:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:23:21 INFO - Installing collected packages: mozsystemmonitor 04:23:21 INFO - Running setup.py install for mozsystemmonitor 04:23:21 INFO - Successfully installed mozsystemmonitor 04:23:21 INFO - Cleaning up... 04:23:21 INFO - Return code: 0 04:23:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:23:21 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:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:21 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:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f278a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f30d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6a84910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, '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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:21 INFO - 'HOME': '/Users/cltbld', 04:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:21 INFO - 'LOGNAME': 'cltbld', 04:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:21 INFO - 'MOZ_NO_REMOTE': '1', 04:23:21 INFO - 'NO_EM_RESTART': '1', 04:23:21 INFO - 'PAGER': '/bin/cat', 04:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:21 INFO - 'PWD': '/builds/slave/test', 04:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:21 INFO - 'SHELL': '/bin/bash', 04:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:23:21 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:21 INFO - 'USER': 'cltbld', 04:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:21 INFO - 'XPC_FLAGS': '0x0', 04:23:21 INFO - 'XPC_SERVICE_NAME': '0', 04:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:21 INFO - Downloading/unpacking jsonschema==2.5.1 04:23:21 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:23:21 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:23:21 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:23:21 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:23:21 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:23:21 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:23:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:23:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:24 INFO - Downloading functools32-3.2.3-2.tar.gz 04:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:23:24 INFO - warning: no files found matching '*.txt' 04:23:24 INFO - no previously-included directories found matching 'build' 04:23:24 INFO - no previously-included directories found matching 'dist' 04:23:24 INFO - no previously-included directories found matching '.git*' 04:23:24 INFO - Installing collected packages: jsonschema, functools32 04:23:24 INFO - Running setup.py install for functools32 04:23:24 INFO - warning: no files found matching '*.txt' 04:23:24 INFO - no previously-included directories found matching 'build' 04:23:24 INFO - no previously-included directories found matching 'dist' 04:23:24 INFO - no previously-included directories found matching '.git*' 04:23:24 INFO - Successfully installed jsonschema functools32 04:23:24 INFO - Cleaning up... 04:23:24 INFO - Return code: 0 04:23:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:23:24 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:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:24 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:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:24 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 0x10f278a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f30d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6a84910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, '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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:23:24 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:23:24 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:23:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:23:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:24 INFO - 'HOME': '/Users/cltbld', 04:23:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:24 INFO - 'LOGNAME': 'cltbld', 04:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:24 INFO - 'MOZ_NO_REMOTE': '1', 04:23:24 INFO - 'NO_EM_RESTART': '1', 04:23:24 INFO - 'PAGER': '/bin/cat', 04:23:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:24 INFO - 'PWD': '/builds/slave/test', 04:23:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:24 INFO - 'SHELL': '/bin/bash', 04:23:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:23:24 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:23:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:24 INFO - 'USER': 'cltbld', 04:23:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:24 INFO - 'XPC_FLAGS': '0x0', 04:23:24 INFO - 'XPC_SERVICE_NAME': '0', 04:23:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:24 INFO - Downloading/unpacking blobuploader==1.2.4 04:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:27 INFO - Downloading blobuploader-1.2.4.tar.gz 04:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:23:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:23:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:28 INFO - Downloading docopt-0.6.1.tar.gz 04:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:23:28 INFO - Installing collected packages: blobuploader, requests, docopt 04:23:28 INFO - Running setup.py install for blobuploader 04:23:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:23:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:23:28 INFO - Running setup.py install for requests 04:23:29 INFO - Running setup.py install for docopt 04:23:29 INFO - Successfully installed blobuploader requests docopt 04:23:29 INFO - Cleaning up... 04:23:29 INFO - Return code: 0 04:23:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23: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:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23: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:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:29 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 0x10f278a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f30d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6a84910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, '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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:23:29 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:23:29 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:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:29 INFO - 'HOME': '/Users/cltbld', 04:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:29 INFO - 'LOGNAME': 'cltbld', 04:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:29 INFO - 'MOZ_NO_REMOTE': '1', 04:23:29 INFO - 'NO_EM_RESTART': '1', 04:23:29 INFO - 'PAGER': '/bin/cat', 04:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:29 INFO - 'PWD': '/builds/slave/test', 04:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:29 INFO - 'SHELL': '/bin/bash', 04:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:23:29 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:29 INFO - 'USER': 'cltbld', 04:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:29 INFO - 'XPC_FLAGS': '0x0', 04:23:29 INFO - 'XPC_SERVICE_NAME': '0', 04:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:29 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-s85ckN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:29 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-6Ds9sH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:29 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-sff47z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:30 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-agZ8Uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:30 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-3rPAFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:30 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-FPdAbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:30 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-aZqnSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:30 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-rtXFni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:30 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-JHwW1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:30 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-TdZzCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:30 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-ptIaT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:30 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-GmnCge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:31 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-qwyRhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:31 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-LeVDYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:31 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-LqicEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:31 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-5ZHvpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:31 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-Hhy_wr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:23:31 INFO - Running setup.py install for manifestparser 04:23:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:23:31 INFO - Running setup.py install for mozcrash 04:23:31 INFO - Running setup.py install for mozdebug 04:23:31 INFO - Running setup.py install for mozdevice 04:23:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:23:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:23:32 INFO - Running setup.py install for mozfile 04:23:32 INFO - Running setup.py install for mozhttpd 04:23:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:23:32 INFO - Running setup.py install for mozinfo 04:23:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:23:32 INFO - Running setup.py install for mozInstall 04:23:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:23:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:23:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:23:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:23:32 INFO - Running setup.py install for mozleak 04:23:32 INFO - Running setup.py install for mozlog 04:23:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:23:33 INFO - Running setup.py install for moznetwork 04:23:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:23:33 INFO - Running setup.py install for mozprocess 04:23:33 INFO - Running setup.py install for mozprofile 04:23:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:23:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:23:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:23:33 INFO - Running setup.py install for mozrunner 04:23:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:23:33 INFO - Running setup.py install for mozscreenshot 04:23:33 INFO - Running setup.py install for moztest 04:23:33 INFO - Running setup.py install for mozversion 04:23:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:23:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:23:34 INFO - Cleaning up... 04:23:34 INFO - Return code: 0 04:23:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:34 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:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:34 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:23:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:34 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 0x10f278a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f30d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6a84910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, '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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:23:34 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:23:34 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:23:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:23:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:34 INFO - 'HOME': '/Users/cltbld', 04:23:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:34 INFO - 'LOGNAME': 'cltbld', 04:23:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:34 INFO - 'MOZ_NO_REMOTE': '1', 04:23:34 INFO - 'NO_EM_RESTART': '1', 04:23:34 INFO - 'PAGER': '/bin/cat', 04:23:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:34 INFO - 'PWD': '/builds/slave/test', 04:23:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:34 INFO - 'SHELL': '/bin/bash', 04:23:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:23:34 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:23:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:34 INFO - 'USER': 'cltbld', 04:23:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:34 INFO - 'XPC_FLAGS': '0x0', 04:23:34 INFO - 'XPC_SERVICE_NAME': '0', 04:23:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:34 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-7fpDyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:34 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:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:34 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-3Ne3Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:34 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:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:34 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-Idm3VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:34 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:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:34 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-ziUYFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:34 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:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:34 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-1UaXgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:34 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:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:34 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-4ZWY_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-R_yVNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:35 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:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-JvAt7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:35 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:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-RnftWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:35 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:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-BlgArO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-tB_rwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:35 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:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-NszGlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-MGuBSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:35 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:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-TJ7qq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-HHOnld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:35 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-7FaoXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:36 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:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:36 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-L3vsBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:38 INFO - Downloading blessings-1.6.tar.gz 04:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:23:38 INFO - Installing collected packages: blessings 04:23:38 INFO - Running setup.py install for blessings 04:23:38 INFO - Successfully installed blessings 04:23:38 INFO - Cleaning up... 04:23:38 INFO - Return code: 0 04:23:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:23:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:39 INFO - Reading from file tmpfile_stdout 04:23:39 INFO - Current package versions: 04:23:39 INFO - blessings == 1.6 04:23:39 INFO - blobuploader == 1.2.4 04:23:39 INFO - docopt == 0.6.1 04:23:39 INFO - functools32 == 3.2.3-2 04:23:39 INFO - jsonschema == 2.5.1 04:23:39 INFO - manifestparser == 1.1 04:23:39 INFO - mozInstall == 1.12 04:23:39 INFO - mozcrash == 0.17 04:23:39 INFO - mozdebug == 0.1 04:23:39 INFO - mozdevice == 0.48 04:23:39 INFO - mozfile == 1.2 04:23:39 INFO - mozhttpd == 0.7 04:23:39 INFO - mozinfo == 0.9 04:23:39 INFO - mozleak == 0.1 04:23:39 INFO - mozlog == 3.2 04:23:39 INFO - moznetwork == 0.27 04:23:39 INFO - mozprocess == 0.23 04:23:39 INFO - mozprofile == 0.28 04:23:39 INFO - mozrunner == 6.12 04:23:39 INFO - mozscreenshot == 0.1 04:23:39 INFO - mozsystemmonitor == 0.3 04:23:39 INFO - moztest == 0.7 04:23:39 INFO - mozversion == 1.4 04:23:39 INFO - psutil == 3.1.1 04:23:39 INFO - requests == 1.2.3 04:23:39 INFO - wsgiref == 0.1.2 04:23:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f278a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f30d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6a84910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, '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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:23:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:39 INFO - 'HOME': '/Users/cltbld', 04:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:39 INFO - 'LOGNAME': 'cltbld', 04:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:39 INFO - 'MOZ_NO_REMOTE': '1', 04:23:39 INFO - 'NO_EM_RESTART': '1', 04:23:39 INFO - 'PAGER': '/bin/cat', 04:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:39 INFO - 'PWD': '/builds/slave/test', 04:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:39 INFO - 'SHELL': '/bin/bash', 04:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:23:39 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:39 INFO - 'USER': 'cltbld', 04:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:39 INFO - 'XPC_FLAGS': '0x0', 04:23:39 INFO - 'XPC_SERVICE_NAME': '0', 04:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:23:39 INFO - Cleaning up... 04:23:39 INFO - Return code: 0 04:23:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f278a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f30d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6a84910b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, '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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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:23:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:39 INFO - 'HOME': '/Users/cltbld', 04:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:39 INFO - 'LOGNAME': 'cltbld', 04:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:39 INFO - 'MOZ_NO_REMOTE': '1', 04:23:39 INFO - 'NO_EM_RESTART': '1', 04:23:39 INFO - 'PAGER': '/bin/cat', 04:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:39 INFO - 'PWD': '/builds/slave/test', 04:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:39 INFO - 'SHELL': '/bin/bash', 04:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:23:39 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:39 INFO - 'USER': 'cltbld', 04:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:39 INFO - 'XPC_FLAGS': '0x0', 04:23:39 INFO - 'XPC_SERVICE_NAME': '0', 04:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:23:39 INFO - Cleaning up... 04:23:39 INFO - Return code: 0 04:23:39 INFO - Running post-action listener: _resource_record_post_action 04:23:39 INFO - Running post-action listener: _start_resource_monitoring 04:23:39 INFO - Starting resource monitoring. 04:23:39 INFO - ##### 04:23:39 INFO - ##### Finished create-virtualenv step (success) 04:23:39 INFO - ##### 04:23:39 INFO - ##### 04:23:39 INFO - ##### Running install step. 04:23:39 INFO - ##### 04:23:39 INFO - Running pre-action listener: _resource_record_pre_action 04:23:39 INFO - Running main action method: install 04:23:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:40 INFO - Reading from file tmpfile_stdout 04:23:40 INFO - Detecting whether we're running mozinstall >=1.0... 04:23:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:23:40 INFO - Reading from file tmpfile_stdout 04:23:40 INFO - Output received: 04:23:40 INFO - Usage: mozinstall [options] installer 04:23:40 INFO - Options: 04:23:40 INFO - -h, --help show this help message and exit 04:23:40 INFO - -d DEST, --destination=DEST 04:23:40 INFO - Directory to install application into. [default: 04:23:40 INFO - "/builds/slave/test"] 04:23:40 INFO - --app=APP Application being installed. [default: firefox] 04:23:40 INFO - mkdir: /builds/slave/test/build/application 04:23:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:24:14 INFO - Reading from file tmpfile_stdout 04:24:14 INFO - Output received: 04:24:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:24:14 INFO - Running post-action listener: _resource_record_post_action 04:24:14 INFO - ##### 04:24:14 INFO - ##### Finished install step (success) 04:24:14 INFO - ##### 04:24:14 INFO - ##### 04:24:14 INFO - ##### Running run-tests step. 04:24:14 INFO - ##### 04:24:14 INFO - Running pre-action listener: _resource_record_pre_action 04:24:14 INFO - Running main action method: run_tests 04:24:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:24:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:24:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:24:14 INFO - 2016-07-11 04:24:14.293 screenresolution[1919:13058] starting screenresolution argv=screenresolution get 04:24:14 INFO - 2016-07-11 04:24:14.296 screenresolution[1919:13058] Display 0: 1600x1200x32@60 04:24:14 INFO - 2016-07-11 04:24:14.302 screenresolution[1920:13060] starting screenresolution argv=screenresolution list 04:24:14 INFO - Available Modes on Display 0 04:24:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:24:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:24:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:24:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:24:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:24:14 INFO - Intel Iris: 04:24:14 INFO - Chipset Model: Intel Iris 04:24:14 INFO - Type: GPU 04:24:14 INFO - Bus: Built-In 04:24:14 INFO - VRAM (Dynamic, Max): 1536 MB 04:24:14 INFO - Vendor: Intel (0x8086) 04:24:14 INFO - Device ID: 0x0a2e 04:24:14 INFO - Revision ID: 0x0009 04:24:14 INFO - Displays: 04:24:14 INFO - Display: 04:24:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:24:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:24:14 INFO - Display Serial Number: 1600x1200 60 04:24:14 INFO - Main Display: Yes 04:24:14 INFO - Mirror: Off 04:24:14 INFO - Online: Yes 04:24:14 INFO - Rotation: Supported 04:24:14 INFO - Return code: 0 04:24:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:24:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:24:14 INFO - Python 2.7.3 04:24:14 INFO - Return code: 0 04:24:14 INFO - grabbing minidump binary from tooltool 04:24:14 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:24:14 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1dcd30>, '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 0x10ef41db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:24:14 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:24:14 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:24:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:24:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:24:14 INFO - Return code: 0 04:24:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:24:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:24:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:24:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:24:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:24:14 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/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--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:24:14 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/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0295 --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:24:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bA8jGT8hIq/Render', 04:24:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:14 INFO - 'HOME': '/Users/cltbld', 04:24:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:14 INFO - 'LOGNAME': 'cltbld', 04:24:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:24:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:24:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:14 INFO - 'MOZ_NO_REMOTE': '1', 04:24:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:24:14 INFO - 'NO_EM_RESTART': '1', 04:24:14 INFO - 'PAGER': '/bin/cat', 04:24:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:14 INFO - 'PWD': '/builds/slave/test', 04:24:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:24:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:14 INFO - 'SHELL': '/bin/bash', 04:24:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sxPpDCgZDh/Listeners', 04:24:14 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 04:24:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:14 INFO - 'USER': 'cltbld', 04:24:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:14 INFO - 'XPC_FLAGS': '0x0', 04:24:14 INFO - 'XPC_SERVICE_NAME': '0', 04:24:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:14 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/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--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:24:14 INFO - mozversion application_buildid: 20160711021108 04:24:14 INFO - mozversion application_changeset: 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 04:24:14 INFO - mozversion application_display_name: Nightly 04:24:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:24:14 INFO - mozversion application_name: Firefox 04:24:14 INFO - mozversion application_remotingname: firefox 04:24:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:24:14 INFO - mozversion application_vendor: Mozilla 04:24:14 INFO - mozversion application_version: 50.0a1 04:24:14 INFO - mozversion platform_buildid: 20160711021108 04:24:14 INFO - mozversion platform_changeset: 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 04:24:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:24:14 INFO - mozversion platform_version: 50.0a1 04:24:14 INFO - using testdate: 1468236254 04:24:14 INFO - actual date: 1468236254 04:24:14 INFO - starting webserver on 'localhost:51518' 04:24:14 INFO - SUITE-START | Running 2 tests 04:24:14 INFO - TEST-START | dromaeo_css 04:24:14 INFO - operating with platform_type : mac_ 04:24:14 INFO - Initialising browser for dromaeo_css test... 04:24:14 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmp9r7ALg/profile http://localhost:51518/getInfo.html) 04:24:17 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 04:24:17 INFO - PROCESS | 1930 | colorDepth:24 04:24:17 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 04:24:17 INFO - PROCESS | 1930 | __metrics 04:24:17 INFO - PROCESS | 1930 | 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:24:17 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:24:18 INFO - TEST-INFO | 1930: exit 0 04:24:18 INFO - Browser initialized. 04:24:18 INFO - Running cycle 1/1 for dromaeo_css test... 04:24:18 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmp9r7ALg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:24:30 INFO - PROCESS | 1932 | RSS: Main: 243798016 04:24:30 INFO - PROCESS | 1932 | 04:25:21 INFO - PROCESS | 1932 | 1468236321699 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:27:32 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51518/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51518/tests/dromaeo/cssquery-ext.html) 04:27:33 INFO - PROCESS | 1932 | RSS: Main: 356646912 04:27:33 INFO - PROCESS | 1932 | 04:30:36 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51518/tests/dromaeo/cssquery-ext.html (next: http://localhost:51518/tests/dromaeo/cssquery-jquery.html) 04:30:37 INFO - PROCESS | 1932 | RSS: Main: 381632512 04:30:37 INFO - PROCESS | 1932 | 04:33:39 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51518/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51518/tests/dromaeo/cssquery-mootools.html) 04:33:39 INFO - PROCESS | 1932 | RSS: Main: 373239808 04:33:39 INFO - PROCESS | 1932 | 04:36:42 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51518/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51518/tests/dromaeo/cssquery-prototype.html) 04:36:42 INFO - PROCESS | 1932 | RSS: Main: 371859456 04:36:42 INFO - PROCESS | 1932 | 04:39:46 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51518/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51518/tests/dromaeo/cssquery-yui.html) 04:39:46 INFO - PROCESS | 1932 | RSS: Main: 345673728 04:39:46 INFO - PROCESS | 1932 | 04:42:49 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:51518/tests/dromaeo/cssquery-yui.html (next: http://localhost:51518/tests/dromaeo/cssquery-dojo.html) 04:42:50 INFO - PROCESS | 1932 | RSS: Main: 389197824 04:42:50 INFO - PROCESS | 1932 | 04:42:50 INFO - PROCESS | 1932 | __start_tp_report 04:42:50 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 04:42:50 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 04:42:50 INFO - PROCESS | 1932 | |i|pagename|runs| 04:42:50 INFO - PROCESS | 1932 | |0;dojo.html;3351.37999470098;3367.8316084195785;3382.6786455286747;3391.6777906098914;3438.6561343865615;5630.09918413054;5693.146028095783;5755.39568345324;5772.971135144324;5814.156947242653;5748.166515855209;5780.190773291732;5851.444112809287;5868.589198756089;5884.146798714184;5146.588272938156;5157.458466860982;5216.087184742674;5251.921221181686;5349.625526213166;5327.307450031491;5330.387005494379;5369.865753356178;5386.811461598825;5400.378956420022;5174.896502069975;5186.818461353834;5224.895502089975;5227.372715274191;5265.736713164346;5188.040212560688;5193.480651934814;5196.558292545101;5241.554467870235;5343.251944727741;5218.634695571312;5227.503387178212;5231.989134559708;5292.629515933886;5302.946970530284;5706.572007099443;5727.513161381287;5847.970760146214;5888.528917686617;5910.645361278337;6231.189945307126;6266.749330026839;6312.305646378894;6313.43179113882;6317.968410157966;6247.593906396115;6247.687615619201;6270.905936410957;6275.96862015687;6281.120643109968;5752.568557358215;5824.621399609012;5877.500412464903;5957.642541447527;5972.313183983854;6247.656378899204;6254.593451425642;6257.687115644199;6257.9687101564205;6272.937270627235;5263.236830659533;6201.782937597162;6219.844503887349;6220.440160385587;6233.0338797486465;6134.620279381772;6140.6929653517145;6181.629102253789;6217.813465596039;6279.591826531261;6109.694515274218;6187.102870083812;6194.628322300587;6207.906881396782;6209.285932117774;6180.351063138396;6225.688715564294;6241.188645476059;6243.719032643542;6272.1219029335925;4928.186849169919;4967.081089998362;4974.577160941288;4987.750612469435;5027.296178535008;4446.377507149002;4542.750148741851;4562.726236425825;4582.9541704582525;4585.380973568593;5807.709614519257;5821.592488525847;5823.213866128019;5844.678542680108;5876;6222.719977601018;6275.403836635513;6280.152179455807;6292.811215663537;6299.433051025429;5695.572832037614;5708.686022268815;5743.62666426681;5798.623089499169;5801.332846722681;5477.260569823103;5481.972590137024;5504.91742623861;5510.5316048135555;5534.1975413564805;6310.7475700971445;6318.557700960981;6320.5891617044745;6321.367863213642;6344.873102537924;6166.951618224823;6202.069689546604;6227.065940108839;6240.594361366497;6301.842453938688;6287.811365659038;6292.937070629235;6313.40022697843;6317.431431171217;6324.462420694293;6276.340984196594;6281.780137695158;6286.654234017173;6286.905696414649;6309.779157729457;6119.204503414528;6146.477549408351;6227.719752611053;6252.218472690914;6296.433321001132;6175.567710260419;6181.690915454299;6182.1345011697495;6182.845428864314;6204.906926396017;6154.230721159855;6164.568480206522;6166.722497487534;6179.474744646755;6185.752569897154;5847.94152058474;5918.881622367615;5937.732802023833;5940.495057919952;6043.09353596964;6130.42522140693;6142.877142457215;6198.318184999736;6214.098955651496;6227.314995350417;6034.517238620812;6047.909281360871;6048.4858787003595;6053.424924632153;6083.695815209133 04:42:50 INFO - PROCESS | 1932 | |1;ext.html;484.93695819543433;516.9948300516995;517.9041877252708;530.1385248970423;538.6256551696572;30446.69553304469;32128.357432851328;32247.19382015448;42389.7880510597;43407;44106.55893441065;44363;44544.77727611366;44545.1090978181;44648.553514464846;9692.951535242331;9891.159251463634;9893.75265618358;9903.95048024759;9941.850872236922;9697.90302096977;9796.461194634292;9799.461029643368;9820.950895245549;9850.162736167435;9767.706968790948;9806.9509652452;9821.558029888673;9823.017698230191;9842.753931151707;9749.220062394992;9792.755181120458;9801.6079356826;9810.754731131707;9852.802943941153;9608.615655373776;9682.56428460721;9700.563474643657;9702.320837541374;9720.027997200294;41964.37053444201;42295;42308.78845605783;42363;42372.78813605943;3477.5479187705437;3614.6023937366867;3684.299983003221;3743.4197699356373;3959.6362277314065;32070.198245043826;33054;33278.334433311196;33388.165295867555;33689.32621347584;3259.282957749291;3431.605365382963;3442.707369873564;3460.653934606544;3462.567179102612;488519.5574022107;489953;489968;490175;495197;56019.71990140105;63713.681431593475;64156.71686566243;64241.218290354096;64246.678766606805;66890.66218675696;66932;67016.32983670101;67082.32917670859;67206.99189512161;16831;17081.743773843402;17091.48725538236;17094.40169594058;17118.48644540666;13001.609951701465;13094.345282735825;13094.934525327313;13107.541236056692;13111.082224244212;15517.45688900883;17182.797204195587;18262.908685456754;18476.353327633467;18497;5243.5318110929;6295.842603934938;6296.905546416808;6321;6428.5178611604315;889.74642226965;974.605284859633;1001.972977697851;1013.2198207380276;1017.4454922067491;27430.45139097207;29797.702022979927;30178.396432071237;30809.949201272946;31163.688363116533;1055.614700634263;1064.99467502662;1068.6526878764432;1158.5655379232787;1162.1690491298762;15838.683226335408;15870.302048651756;15881.444149454714;16210.513684589481;16341.918290408472;662255;668096;671611;672424;672521;58321;62044;62310.68844655748;62358;62585.374146258866;1271.1610337177603;1329.2954733991;1339.5780329196273;1381.2679279981626;1434.4620767212296;26506.602400964388;30997;31585.52621710722;31777.8411107943;31849.737850928024;52707;53329.733351332994;53341.733291333294;53382.19926701025;53434.664133396975;501677;517586;517639;518317.5345972389;519515.0242487936;28651.57022644704;36418.45372319364;36992.81503592465;42270;43216.56783432126;49432.50567494279;49471.50528494669;49498.75250623724;50532;50548.73628159326;39298.80350598229;40132.1973560533;41108.97227569335;42829.929251768954;44652.10695786131;41127;41240.1751964953;41509.75470735883;41664;42728.5727142737;40214.597854021085;40728.77813665595;41060.38409423801;41216.38175427311;41221.76334709964;34899.825500872335;35853.64146358503;36242.63757362393;36455.453168201966;36564.634353656125;25799.613005804553;25821.575125079587;25876.482470350864;25941.740582594688;27038.864805676632 04:42:50 INFO - PROCESS | 1932 | |2;jquery.html;361.3892521638429;384.35812193636633;391.4715134568333;392.25471603952485;415.05781875143424;15492.978720235878;17808.821911780877;21461.892690536566;21493.892530537345;21507.46231344217;20899.686504702404;21539.353819385444;21603.243886463977;21617.891910540464;21647.891760541173;5359.705216213107;5732.254806875121;5847.766089356421;5927.1998280232265;5935.9406405935815;5941.405859414067;5957.136215248786;5973.074173503092;6012.158297838306;6042.546808989321;4983;5877.735501902424;5973.910391344133;5978.432048955342;5991.850203744898;5731.713414329267;5920.970395148018;5976;5998.6100903441575;6003.30961939376;5175.171972484384;5201.765920533548;5204.895902081975;5210.843674689765;5211.7133557654315;25565.10522131735;25624.743752562423;25698.8435520402;26099.347516312053;26099.82550785219;2307.2962746362355;2394.7246066702205;2443.218170185541;2473.5547601431736;2545.121932933135;15506.61233469161;15890.36438542457;16370.83629163717;16417.58956026097;16453.58866028347;1907.8092190780949;1918.6834172361544;1940.6797878350055;1968.1635304995464;1993.7308463357551;115985;118036;118068;118443;122799;10594.311369761095;10840.620578279722;11062.446877656084;11353.148513861328;11359.148063895242;12675.93662031684;13839.930800345934;13979.930100349433;13981.650458738613;14081.788773168411;8664.350173736995;8875.423097498771;8890.73327800167;8929.821403572023;8963.238124759338;7452.739154129579;8954.89658609519;8976;9128.726138215865;9161.679341223158;6752.831179220559;6849.04113424121;6867.862642747217;6870.175578930561;6882.621455819978;1764.97352539712;1875.2124107874724;1895.8293753562245;1896.5637903282322;1918;946.4132238012339;1016.3901659004538;1035.9119474844722;1042.5360714482135;1049.8740151181755;18425.90787046083;21966.560668786537;23471.765282346958;23564.41088972755;23672.64491032636;956.416585882611;971.8396464583265;1031.783325501638;1048.3919326790444;1058.8729352477599;11673.88326116745;12138.757224855453;12404.81392779109;12574.308413037188;12753.936230318788;519909;587295;588490;601431;602268;83822.16177838265;107194;107431;120917.39541302237;122970;999.7800483893532;1006.8741407324085;1021.1677482851452;1040.8594839696543;1042.8070806900748;16096.597585060466;20522.794772052686;20612.381628551168;20693.482662933548;20709.58580828405;52929;53009;53219.93560128698;56268.87462250814;57096.42903570911;75793;76300;77257.76727879308;77461;77824;7749.573773442515;7782.961085194538;8030.43786934909;8234;8376.288015518505;13633.454661813814;13704.10923289983;13735.519256825963;13980.161190328612;13984.650383740487;11657.650270491898;11661.825072624088;11808.82286765682;11930.522779089086;11959.461824217757;11640.88359116398;11649.417529123679;11670.824937626112;11816.881831181578;11931.343776092399;11706.590269340531;11783.52865885355;11871.643850684493;12017.459214335551;12123.151379403356;8140.145284745254;8161;8214.794630134294;8267.751967440987;8430.83138337242;11792.823107653221;11796.587119451122;11827.822582661094;11964.880351196376;11974.580889669169 04:42:50 INFO - PROCESS | 1932 | |3;mootools.html;1565.6320764620311;1590.5625952862965;1825.7900341460736;1951.190256043742;1959.098814545309;8550.91449085509;8860.64557417704;9022.684206052789;9044.8643270351;9046.63813447462;7893.842123157533;8134.308583770372;8524.445911015795;8736.956315218415;8833.469991800497;2349.3421841884256;2385.3082606044227;2385.7713277661933;2387.3554140382084;2389.3668177932864;2108.926187583435;2355.717313922332;2357.834951553392;2399.7240317363467;2400.987995060022;2160.2655097266925;2323.0939933425975;2379.5003049359657;2388.164142550111;2392.7009123859516;2347.6009078456705;2361.1499860050367;2370.530271231847;2373.703287089114;2382.2376839411395;2328.371339738278;2334.1830359374253;2340.157543284416;2344.284993077111;2357.634566642173;8811.647534098629;8950.925888893375;8956.014838367775;8957.731268061969;8976.8653470198;4686.953130468686;5226.05930932432;5392.9191062133705;5394.568434525228;5397.568194544427;3470.9826450867836;3576.6423357664285;3630.8547658093644;3636.8727094551564;3638.7270954678506;1072.9892701073045;1088.6353071720982;1096.6764804382647;1109.3066833229232;1113.6158025481195;11655.825162622568;11728.94135529317;11732.413379331;11736.471858766376;11749.530018799238;4096.566201829395;4507.211238033331;4628.560286772751;4770.522947705271;4792.664513484023;9907;9946.055124763136;9957.55191016406;9966.302358834957;9993.500324983721;5344.305240318734;5349.983503134392;5353.785848566014;5354;5361.222622719683;4724.95275047252;4896.167651499253;4896.1921282987905;4898.755062246944;4922.335484709519;3785.640364165455;3800.9429858552144;3816.8282427290283;3866.5360156781367;3892.0853599404118;1107.0755918807724;1112.6161474291357;1114.8272017837248;1174.3188950408742;1177.652592485219;713.6717110129294;895;923.1922068190333;944.4710961861242;987.980240395188;5820.505257053113;5901.645901245939;5934.902043122036;5962.940370596325;6030.698465076729;924.4961495984704;940.586142097475;946.4888959961681;947.3321308477507;976.6630512473185;3855.2867719471988;3933.8033098344968;3988.12261302513;4005.1589166274825;4073.816678249427;10490.31812932157;10820.783584328427;10969.067629251604;10976.286541374944;10992.505337259843;8776.912230877737;10913.563457461612;10916.836247456295;10950.233483656228;10960;7938.841223175619;8220.753377398687;8292.460990035628;8384.203500667427;8415.410921235454;5991.400859913973;6019.488343490677;6023.216981792339;6196.597221180609;6271.937280627135;9982.251331150048;9986.400815951067;10100.747481313027;10123.7469063274;10127.392356458637;10360.792784144425;10381.377117372982;10395.064444200058;10395.272330937065;10398.792024159626;6947.3747362737595;6960.443164546723;7004.929950700428;7018.2630823762765;7316.853662926818;8396.87404688918;8429.831403371776;8505.277051450448;8512.489250644981;8530.872036919576;6267.749290028348;7432.777016689508;7572.810679733051;7619.1999840016;7719.614019299125;7345.6694448751;7442.590657513889;7481.663325150273;7552;7562.584057876649;7112.466565007541;7133.072700548895;7180.533265337736;7182.784516464514;7203.747868824566;4928.654994150375;5104.1067813133295;5106.719130447861;5140.228965655182;5191.974040129775;4981;4995.950040499694;5059.772310246121;5091.821786237464;5189.896202076013 04:42:50 INFO - PROCESS | 1932 | |4;prototype.html;310.55900621118013;317.24179211684077;321.50969771099074;323.3952508808528;328.6040321412698;4595.73617255255;4840.80636774529;5473.644213126149;5600.907822974522;5623.26897503325;4875.780589873455;5502.889942201154;5518;5588.804391846286;5603.831885043445;3500.702440292578;3967.9404808927816;4159.230542349675;4220.472440944882;4251.341042138473;3496.199457279503;4109.602735070075;4200.390943313218;4219.303814870543;4240.533541310453;3522.4716292556195;3667.44988251763;4154.459920210368;4181.435506206669;4187.120704652027;3300.9766972238563;4112.3420252759715;4119.855805046839;4161.417401563784;4184.14225083857;3022.9395412091853;3348.5479460272795;3578.35589593873;3612.710983121344;3648.434492653643;4513.548645135493;5058.721770302632;5233.738313084331;5238.319018527605;5337.277988226033;4338.132373525276;4354.629856462205;4856.757162141878;4890.899547601797;4890.902181956341;5130.358705161855;5318.388385335697;5344.759485823147;5789.102689083199;5810.622309549908;4058.15354013923;4063.89840253996;4103.938440923389;4106.77412742299;4126.154138401621;4711.316859055418;5143.897122057575;5797.73910174039;5894.764209431618;6065.575409721366;3672.926541469209;3941.6452519273403;4072.9592704073175;4117.711760176759;4123.299039163349;4194.42708984131;4211.115665710174;4211.7996371034105;4222.767747773902;4230.513490948519;4475.239209334421;4798.568128868418;4803.327534145223;4843.95156048435;4883.533815423189;5797.36229014808;5833.941660583425;5841.824745257649;5858.707064646751;5871.55963302754;1366.4124426496503;1387.840398354182;1393.7560926837764;1396.9720605587831;1399.5794268817158;790.4743345675176;835.2023817254508;843.6627944707584;845.2815107158866;847.9576021198916;5190;5195.948040519546;5204.609654275944;5212.765425555859;5314.893702125936;5057.494250574987;5079.796808127633;5551.472610102034;5953.642781433128;5973.551983601247;4387.714798538085;5376.731163441812;5442.183672449085;5460;5486.31421072366;2768.764655004307;2993.3414648777234;3018.517037274026;3137.9215519612194;3149.7952633078776;5887.852803679943;6097.512199024068;6122.724477398528;6180.165677633552;6186.226721659793;5771.249737534094;5962.701864906738;6019.605687847896;6075.939240607538;6157;5129.794808207705;5640.802571909963;5978.850528736816;6014.398560143951;6102.91802639673;5058.974705126451;5720;5963.791267305797;6000.309964354155;6034.939650603438;4981.825636102719;5858.560607954506;5907.675077870586;5923.585349025527;5923.9420996617455;5912.314990227765;6161.507079433724;6170.074488826533;6173.506119510339;6225.536998805186;3703.185299234164;4025.852631999866;4030.3551431770775;4056.9188616228103;4059.6346328830546;6041.979010494752;6054.848628784315;6066.575339726177;6067.726952287069;6072.696365181635;5064.328771505321;5119.974400128124;5202.907389448107;5459.645123066988;5912.526997840248;5299.913517728934;5390.460953904578;5521.779128834802;5727.706568722918;5807.535397168132;4770.976145119253;5443.401225865073;5688.488036076773;5697.57268204878;5751.597388182954;5536.117719975227;5636.196417146473;5753.9136912945505;5851.297844258716;5881.588288819741;5225.973870130625;5624.971875140598;5668.2064510967675;5686.545076393796;5696.914546281892 04:42:50 INFO - PROCESS | 1932 | |5;yui.html;745.5265906149596;777.9730755402867;813.9215539410283;852.9744107676768;857.1899554920599;17067;18473.35343262986;18560.814391856078;18573.97843118628;18578.885266883997;17253.396131135414;17399.130043497807;17427.82572174281;17444.30222791087;17501.91249043753;738.9002484664583;911.4759013567192;929.326238477102;932.0818993291603;933.8225737109962;744.04761904762;914.063085337531;938.1837801113041;942.3309450290302;945.0691069296737;969.9515024248794;978.9510524473783;979.1383582447436;982.1111893736145;982.6757170133839;762.161622215564;787.46845879032;969.4571040217465;971.6162115964155;976.6581696406237;578.9826305210851;582.2954225387286;611.871506983534;719.2196466833492;720.9026781384498;15646.608834779221;15704;15704.272530021803;15722.135282559455;15829.841701582953;13534.120282181726;13658.795118073236;13785.379657915419;13983.300834958312;13997.580072597839;10809.567617295299;12634.936825315906;13107.410166542433;13269.668258293523;13601.659958501017;3924.2740093082584;4590.74750888701;4591.494935557086;4730.526947305277;4781.713097214179;92915;97745.5112724439;108316;109873.45063274633;110034.9999999992;10520.894791052144;10704.732381690364;10752.569897204025;10808.675739727822;11111.888881111247;22498.88750556237;22616.208432704778;22629.321120366414;22642.886785566297;22905.08379664828;8156.959215204005;8339.708110216114;8450.112738162527;8503.319734421231;8578.785530361665;4044.292248856438;4602.884927876763;4616.446026476844;4699;4802.879928001758;3541.451075083366;3709.5919448860604;3717.5910649828497;3717.5910649828497;3720.479132921393;558.4471373340364;623.2365352443311;629.2511910826103;639.191422850095;646.544186348623;531.9069610216578;546.5955193157115;563.5181919458886;636.726207730671;706.2478460439615;9607.951960240294;9650.806983860284;9731.270154738422;9770.41377517337;9833.508324583741;595.3778301674761;624.2041397218492;635.9745610175634;654.3881470824793;688.102026854959;4468.128714900563;4559.316102584638;4722.150012997693;4737.242041273448;4794.904101918012;92065;123904;138155;145089;146687;125064;130473;131682;133706;135665;101313.49343253384;102938;110202.44898775454;110359.89640103657;110774;9086.500242486727;9105.772355690895;9139.77150571241;9161.221296189899;9289.907100928904;44234.11531769411;56593.71703141458;57304.71347643235;57842.7107864458;58341.70829145827;87226;91260;91498;91917.54041230018;93106.53446732994;11710.414479275832;11845.170838041255;11921.761564768483;12281;12401.193922395016;12707.93646031764;13163.60509184726;13194.736105277649;13278.601641950758;13369.866301336862;11768.293902365886;12408.193467424588;12443.377831108228;12524.185927914656;12667.80998285008;11779.52881884715;11853.644390668294;12105.576304829288;12231;12271.263724176579;12102.818825282504;12200.938995304967;12209.816852747039;12231.755364892475;12340.81488777651;9136.219261271763;10457.529411176667;10637;10894.782104358026;11062.612808551663;10340.37957722539;10728.785424291627;10755.300905441121;10759.946200268949;10791.244612877023 04:42:50 INFO - PROCESS | 1932 | __end_tp_report 04:42:50 INFO - PROCESS | 1932 | __start_cc_report 04:42:50 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,1302 04:42:50 INFO - PROCESS | 1932 | __end_cc_report 04:42:50 INFO - PROCESS | 1932 | __startTimestamp1468237370145__endTimestamp 04:42:50 INFO - PROCESS | 1932 | 04:42:50 INFO - PROCESS | 1932 | ------- Summary: start ------- 04:42:50 INFO - PROCESS | 1932 | Number of tests: 6 04:42:50 INFO - PROCESS | 1932 | 04:42:50 INFO - PROCESS | 1932 | [#0] dojo.html Cycles:165 Average:5792.10 Median:6047.91 stddev:627.91 (10.4%) stddev-sans-first:599.94 04:42:50 INFO - PROCESS | 1932 | Values: 3351.4 3367.8 3382.7 3391.7 3438.7 5630.1 5693.1 5755.4 5773.0 5814.2 5748.2 5780.2 5851.4 5868.6 5884.1 5146.6 5157.5 5216.1 5251.9 5349.6 5327.3 5330.4 5369.9 5386.8 5400.4 5174.9 5186.8 5224.9 5227.4 5265.7 5188.0 5193.5 5196.6 5241.6 5343.3 5218.6 5227.5 5232.0 5292.6 5302.9 5706.6 5727.5 5848.0 5888.5 5910.6 6231.2 6266.7 6312.3 6313.4 6318.0 6247.6 6247.7 6270.9 6276.0 6281.1 5752.6 5824.6 5877.5 5957.6 5972.3 6247.7 6254.6 6257.7 6258.0 6272.9 5263.2 6201.8 6219.8 6220.4 6233.0 6134.6 6140.7 6181.6 6217.8 6279.6 6109.7 6187.1 6194.6 6207.9 6209.3 6180.4 6225.7 6241.2 6243.7 6272.1 4928.2 4967.1 4974.6 4987.8 5027.3 4446.4 4542.8 4562.7 4583.0 4585.4 5807.7 5821.6 5823.2 5844.7 5876.0 6222.7 6275.4 6280.2 6292.8 6299.4 5695.6 5708.7 5743.6 5798.6 5801.3 5477.3 5482.0 5504.9 5510.5 5534.2 6310.7 6318.6 6320.6 6321.4 6344.9 6167.0 6202.1 6227.1 6240.6 6301.8 6287.8 6292.9 6313.4 6317.4 6324.5 6276.3 6281.8 6286.7 6286.9 6309.8 6119.2 6146.5 6227.7 6252.2 6296.4 6175.6 6181.7 6182.1 6182.8 6204.9 6154.2 6164.6 6166.7 6179.5 6185.8 5847.9 5918.9 5937.7 5940.5 6043.1 6130.4 6142.9 6198.3 6214.1 6227.3 6034.5 6047.9 6048.5 6053.4 6083.7 04:42:50 INFO - PROCESS | 1932 | 04:42:50 INFO - PROCESS | 1932 | [#1] ext.html Cycles:180 Average:70533.86 Median:42367.89 stddev:150485.79 (355.2%) stddev-sans-first:150816.04 04:42:50 INFO - PROCESS | 1932 | Values: 484.9 517.0 517.9 530.1 538.6 30446.7 32128.4 32247.2 42389.8 43407.0 44106.6 44363.0 44544.8 44545.1 44648.6 9693.0 9891.2 9893.8 9904.0 9941.9 9697.9 9796.5 9799.5 9821.0 9850.2 9767.7 9807.0 9821.6 9823.0 9842.8 9749.2 9792.8 9801.6 9810.8 9852.8 9608.6 9682.6 9700.6 9702.3 9720.0 41964.4 42295.0 42308.8 42363.0 42372.8 3477.5 3614.6 3684.3 3743.4 3959.6 32070.2 33054.0 33278.3 33388.2 33689.3 3259.3 3431.6 3442.7 3460.7 3462.6 488519.6 489953.0 489968.0 490175.0 495197.0 56019.7 63713.7 64156.7 64241.2 64246.7 66890.7 66932.0 67016.3 67082.3 67207.0 16831.0 17081.7 17091.5 17094.4 17118.5 13001.6 13094.3 13094.9 13107.5 13111.1 15517.5 17182.8 18262.9 18476.4 18497.0 5243.5 6295.8 6296.9 6321.0 6428.5 889.7 974.6 1002.0 1013.2 1017.4 27430.5 29797.7 30178.4 30809.9 31163.7 1055.6 1065.0 1068.7 1158.6 1162.2 15838.7 15870.3 15881.4 16210.5 16341.9 662255.0 668096.0 671611.0 672424.0 672521.0 58321.0 62044.0 62310.7 62358.0 62585.4 1271.2 1329.3 1339.6 1381.3 1434.5 26506.6 30997.0 31585.5 31777.8 31849.7 52707.0 53329.7 53341.7 53382.2 53434.7 501677.0 517586.0 517639.0 518317.5 519515.0 28651.6 36418.5 36992.8 42270.0 43216.6 49432.5 49471.5 49498.8 50532.0 50548.7 39298.8 40132.2 41109.0 42829.9 44652.1 41127.0 41240.2 41509.8 41664.0 42728.6 40214.6 40728.8 41060.4 41216.4 41221.8 34899.8 35853.6 36242.6 36455.5 36564.6 25799.6 25821.6 25876.5 25941.7 27038.9 04:42:50 INFO - PROCESS | 1932 | 04:42:50 INFO - PROCESS | 1932 | [#2] jquery.html Cycles:180 Average:34460.71 Median:21807.23 stddev:96558.02 (442.8%) stddev-sans-first:96794.95 04:42:50 INFO - PROCESS | 1932 | Values: 361.4 384.4 391.5 392.3 415.1 15493.0 17808.8 21461.9 21493.9 21507.5 20899.7 21539.4 21603.2 21617.9 21647.9 5359.7 5732.3 5847.8 5927.2 5935.9 5941.4 5957.1 5973.1 6012.2 6042.5 4983.0 5877.7 5973.9 5978.4 5991.9 5731.7 5921.0 5976.0 5998.6 6003.3 5175.2 5201.8 5204.9 5210.8 5211.7 25565.1 25624.7 25698.8 26099.3 26099.8 2307.3 2394.7 2443.2 2473.6 2545.1 15506.6 15890.4 16370.8 16417.6 16453.6 1907.8 1918.7 1940.7 1968.2 1993.7 115985.0 118036.0 118068.0 118443.0 122799.0 10594.3 10840.6 11062.4 11353.1 11359.1 12675.9 13839.9 13979.9 13981.7 14081.8 8664.4 8875.4 8890.7 8929.8 8963.2 7452.7 8954.9 8976.0 9128.7 9161.7 6752.8 6849.0 6867.9 6870.2 6882.6 1765.0 1875.2 1895.8 1896.6 1918.0 946.4 1016.4 1035.9 1042.5 1049.9 18425.9 21966.6 23471.8 23564.4 23672.6 956.4 971.8 1031.8 1048.4 1058.9 11673.9 12138.8 12404.8 12574.3 12753.9 519909.0 587295.0 588490.0 601431.0 602268.0 83822.2 107194.0 107431.0 120917.4 122970.0 999.8 1006.9 1021.2 1040.9 1042.8 16096.6 20522.8 20612.4 20693.5 20709.6 52929.0 53009.0 53219.9 56268.9 57096.4 75793.0 76300.0 77257.8 77461.0 77824.0 7749.6 7783.0 8030.4 8234.0 8376.3 13633.5 13704.1 13735.5 13980.2 13984.7 11657.7 11661.8 11808.8 11930.5 11959.5 11640.9 11649.4 11670.8 11816.9 11931.3 11706.6 11783.5 11871.6 12017.5 12123.2 8140.1 8161.0 8214.8 8267.8 8430.8 11792.8 11796.6 11827.8 11964.9 11974.6 04:42:50 INFO - PROCESS | 1932 | 04:42:50 INFO - PROCESS | 1932 | [#3] mootools.html Cycles:180 Average:5740.29 Median:5027.86 stddev:3223.71 (64.1%) stddev-sans-first:3217.49 04:42:50 INFO - PROCESS | 1932 | Values: 1565.6 1590.6 1825.8 1951.2 1959.1 8550.9 8860.6 9022.7 9044.9 9046.6 7893.8 8134.3 8524.4 8737.0 8833.5 2349.3 2385.3 2385.8 2387.4 2389.4 2108.9 2355.7 2357.8 2399.7 2401.0 2160.3 2323.1 2379.5 2388.2 2392.7 2347.6 2361.1 2370.5 2373.7 2382.2 2328.4 2334.2 2340.2 2344.3 2357.6 8811.6 8950.9 8956.0 8957.7 8976.9 4687.0 5226.1 5392.9 5394.6 5397.6 3471.0 3576.6 3630.9 3636.9 3638.7 1073.0 1088.6 1096.7 1109.3 1113.6 11655.8 11728.9 11732.4 11736.5 11749.5 4096.6 4507.2 4628.6 4770.5 4792.7 9907.0 9946.1 9957.6 9966.3 9993.5 5344.3 5350.0 5353.8 5354.0 5361.2 4725.0 4896.2 4896.2 4898.8 4922.3 3785.6 3800.9 3816.8 3866.5 3892.1 1107.1 1112.6 1114.8 1174.3 1177.7 713.7 895.0 923.2 944.5 988.0 5820.5 5901.6 5934.9 5962.9 6030.7 924.5 940.6 946.5 947.3 976.7 3855.3 3933.8 3988.1 4005.2 4073.8 10490.3 10820.8 10969.1 10976.3 10992.5 8776.9 10913.6 10916.8 10950.2 10960.0 7938.8 8220.8 8292.5 8384.2 8415.4 5991.4 6019.5 6023.2 6196.6 6271.9 9982.3 9986.4 10100.7 10123.7 10127.4 10360.8 10381.4 10395.1 10395.3 10398.8 6947.4 6960.4 7004.9 7018.3 7316.9 8396.9 8429.8 8505.3 8512.5 8530.9 6267.7 7432.8 7572.8 7619.2 7719.6 7345.7 7442.6 7481.7 7552.0 7562.6 7112.5 7133.1 7180.5 7182.8 7203.7 4928.7 5104.1 5106.7 5140.2 5192.0 4981.0 4996.0 5059.8 5091.8 5189.9 04:42:50 INFO - PROCESS | 1932 | 04:42:50 INFO - PROCESS | 1932 | [#4] prototype.html Cycles:180 Average:4678.81 Median:5208.69 stddev:1443.93 (27.7%) stddev-sans-first:1410.27 04:42:50 INFO - PROCESS | 1932 | Values: 310.6 317.2 321.5 323.4 328.6 4595.7 4840.8 5473.6 5600.9 5623.3 4875.8 5502.9 5518.0 5588.8 5603.8 3500.7 3967.9 4159.2 4220.5 4251.3 3496.2 4109.6 4200.4 4219.3 4240.5 3522.5 3667.4 4154.5 4181.4 4187.1 3301.0 4112.3 4119.9 4161.4 4184.1 3022.9 3348.5 3578.4 3612.7 3648.4 4513.5 5058.7 5233.7 5238.3 5337.3 4338.1 4354.6 4856.8 4890.9 4890.9 5130.4 5318.4 5344.8 5789.1 5810.6 4058.2 4063.9 4103.9 4106.8 4126.2 4711.3 5143.9 5797.7 5894.8 6065.6 3672.9 3941.6 4073.0 4117.7 4123.3 4194.4 4211.1 4211.8 4222.8 4230.5 4475.2 4798.6 4803.3 4844.0 4883.5 5797.4 5833.9 5841.8 5858.7 5871.6 1366.4 1387.8 1393.8 1397.0 1399.6 790.5 835.2 843.7 845.3 848.0 5190.0 5195.9 5204.6 5212.8 5314.9 5057.5 5079.8 5551.5 5953.6 5973.6 4387.7 5376.7 5442.2 5460.0 5486.3 2768.8 2993.3 3018.5 3137.9 3149.8 5887.9 6097.5 6122.7 6180.2 6186.2 5771.2 5962.7 6019.6 6075.9 6157.0 5129.8 5640.8 5978.9 6014.4 6102.9 5059.0 5720.0 5963.8 6000.3 6034.9 4981.8 5858.6 5907.7 5923.6 5923.9 5912.3 6161.5 6170.1 6173.5 6225.5 3703.2 4025.9 4030.4 4056.9 4059.6 6042.0 6054.8 6066.6 6067.7 6072.7 5064.3 5120.0 5202.9 5459.6 5912.5 5299.9 5390.5 5521.8 5727.7 5807.5 4771.0 5443.4 5688.5 5697.6 5751.6 5536.1 5636.2 5753.9 5851.3 5881.6 5226.0 5625.0 5668.2 5686.5 5696.9 04:42:50 INFO - PROCESS | 1932 | 04:42:50 INFO - PROCESS | 1932 | [#5] yui.html Cycles:180 Average:23975.75 Median:3822.38 stddev:37550.56 (982.4%) stddev-sans-first:37615.39 04:42:50 INFO - PROCESS | 1932 | Values: 745.5 778.0 813.9 853.0 857.2 17067.0 18473.4 18560.8 18574.0 18578.9 17253.4 17399.1 17427.8 17444.3 17501.9 738.9 911.5 929.3 932.1 933.8 744.0 914.1 938.2 942.3 945.1 970.0 979.0 979.1 982.1 982.7 762.2 787.5 969.5 971.6 976.7 579.0 582.3 611.9 719.2 720.9 15646.6 15704.0 15704.3 15722.1 15829.8 13534.1 13658.8 13785.4 13983.3 13997.6 10809.6 12634.9 13107.4 13269.7 13601.7 3924.3 4590.7 4591.5 4730.5 4781.7 92915.0 97745.5 108316.0 109873.5 110035.0 10520.9 10704.7 10752.6 10808.7 11111.9 22498.9 22616.2 22629.3 22642.9 22905.1 8157.0 8339.7 8450.1 8503.3 8578.8 4044.3 4602.9 4616.4 4699.0 4802.9 3541.5 3709.6 3717.6 3717.6 3720.5 558.4 623.2 629.3 639.2 646.5 531.9 546.6 563.5 636.7 706.2 9608.0 9650.8 9731.3 9770.4 9833.5 595.4 624.2 636.0 654.4 688.1 4468.1 4559.3 4722.2 4737.2 4794.9 92065.0 123904.0 138155.0 145089.0 146687.0 125064.0 130473.0 131682.0 133706.0 135665.0 101313.5 102938.0 110202.4 110359.9 110774.0 9086.5 9105.8 9139.8 9161.2 9289.9 44234.1 56593.7 57304.7 57842.7 58341.7 87226.0 91260.0 91498.0 91917.5 93106.5 11710.4 11845.2 11921.8 12281.0 12401.2 12707.9 13163.6 13194.7 13278.6 13369.9 11768.3 12408.2 12443.4 12524.2 12667.8 11779.5 11853.6 12105.6 12231.0 12271.3 12102.8 12200.9 12209.8 12231.8 12340.8 9136.2 10457.5 10637.0 10894.8 11062.6 10340.4 10728.8 10755.3 10759.9 10791.2 04:42:50 INFO - PROCESS | 1932 | -------- Summary: end -------- 04:42:50 INFO - PROCESS | 1932 | 04:42:50 INFO - TEST-INFO | 1932: exit 0 04:42:50 INFO - TEST-OK | dromaeo_css | took 1115921ms 04:42:50 INFO - TEST-START | kraken 04:42:50 INFO - operating with platform_type : mac_ 04:42:50 INFO - Initialising browser for kraken test... 04:42:50 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpvozvj8/profile http://localhost:51518/getInfo.html) 04:42:52 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 04:42:52 INFO - PROCESS | 1937 | colorDepth:24 04:42:52 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 04:42:52 INFO - PROCESS | 1937 | __metrics 04:42:52 INFO - PROCESS | 1937 | 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:42:52 INFO - PROCESS | 1937 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:42:53 INFO - TEST-INFO | 1937: exit 0 04:42:53 INFO - Browser initialized. 04:42:53 INFO - Running cycle 1/1 for kraken test... 04:42:53 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpvozvj8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:43:05 INFO - PROCESS | 1938 | RSS: Main: 243736576 04:43:05 INFO - PROCESS | 1938 | 04:43:08 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51518/tests/kraken/driver.html?testName=audio-beat-detection) 04:43:09 INFO - PROCESS | 1938 | RSS: Main: 294313984 04:43:09 INFO - PROCESS | 1938 | 04:43:11 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51518/tests/kraken/driver.html?testName=audio-dft) 04:43:12 INFO - PROCESS | 1938 | RSS: Main: 305094656 04:43:12 INFO - PROCESS | 1938 | 04:43:15 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51518/tests/kraken/driver.html?testName=audio-fft) 04:43:16 INFO - PROCESS | 1938 | RSS: Main: 389263360 04:43:16 INFO - PROCESS | 1938 | 04:43:18 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51518/tests/kraken/driver.html?testName=audio-oscillator) 04:43:19 INFO - PROCESS | 1938 | RSS: Main: 289394688 04:43:19 INFO - PROCESS | 1938 | 04:43:21 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51518/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:43:21 INFO - PROCESS | 1938 | RSS: Main: 293318656 04:43:21 INFO - PROCESS | 1938 | 04:43:24 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51518/tests/kraken/driver.html?testName=imaging-darkroom) 04:43:25 INFO - PROCESS | 1938 | RSS: Main: 329478144 04:43:25 INFO - PROCESS | 1938 | 04:43:28 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51518/tests/kraken/driver.html?testName=imaging-desaturate) 04:43:28 INFO - PROCESS | 1938 | RSS: Main: 331476992 04:43:28 INFO - PROCESS | 1938 | 04:43:31 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51518/tests/kraken/driver.html?testName=json-parse-financial) 04:43:32 INFO - PROCESS | 1938 | RSS: Main: 333279232 04:43:32 INFO - PROCESS | 1938 | 04:43:33 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51518/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:43:34 INFO - PROCESS | 1938 | RSS: Main: 314576896 04:43:34 INFO - PROCESS | 1938 | 04:43:36 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51518/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:43:37 INFO - PROCESS | 1938 | RSS: Main: 321413120 04:43:37 INFO - PROCESS | 1938 | 04:43:39 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51518/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:43:39 INFO - PROCESS | 1938 | RSS: Main: 318713856 04:43:39 INFO - PROCESS | 1938 | 04:43:42 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51518/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:43:43 INFO - PROCESS | 1938 | RSS: Main: 327852032 04:43:43 INFO - PROCESS | 1938 | 04:43:46 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51518/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:43:46 INFO - PROCESS | 1938 | RSS: Main: 329170944 04:43:46 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51518/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51518/tests/kraken/driver.html?testName=ai-astar) 04:43:48 INFO - PROCESS | 1938 | RSS: Main: 372981760 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | __start_tp_report 04:43:48 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 04:43:48 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 04:43:48 INFO - PROCESS | 1938 | |i|pagename|runs| 04:43:48 INFO - PROCESS | 1938 | |0;ai-astar;100;112;112;111;109;113;112;112;112;111 04:43:48 INFO - PROCESS | 1938 | |1;audio-beat-detection;144;107;104;158;107;105;128;105;139;107 04:43:48 INFO - PROCESS | 1938 | |2;audio-dft;185;185;183;182;182;180;181;186;184;185 04:43:48 INFO - PROCESS | 1938 | |3;audio-fft;80;73;57;109;58;57;64;63;99;78 04:43:48 INFO - PROCESS | 1938 | |4;audio-oscillator;82;93;92;72;70;68;97;70;68;98 04:43:48 INFO - PROCESS | 1938 | |5;imaging-gaussian-blur;87;84;87;84;87;84;88;84;86;84 04:43:48 INFO - PROCESS | 1938 | |6;imaging-darkroom;92;92;93;93;93;93;93;92;93;91 04:43:48 INFO - PROCESS | 1938 | |7;imaging-desaturate;84;86;100;140;134;69;130;130;136;85 04:43:48 INFO - PROCESS | 1938 | |8;json-parse-financial;56;55;55;55;53;55;53;53;53;56 04:43:48 INFO - PROCESS | 1938 | |9;json-stringify-tinderbox;66;74;69;77;72;72;72;72;71;71 04:43:48 INFO - PROCESS | 1938 | |10;stanford-crypto-aes;84;81;83;82;82;81;85;80;85;85 04:43:48 INFO - PROCESS | 1938 | |11;stanford-crypto-ccm;133;145;147;140;145;151;144;137;178;134 04:43:48 INFO - PROCESS | 1938 | |12;stanford-crypto-pbkdf2;201;194;162;162;162;172;160;162;162;162 04:43:48 INFO - PROCESS | 1938 | |13;stanford-crypto-sha256-iterative;65;67;66;66;66;67;66;66;65;65 04:43:48 INFO - PROCESS | 1938 | __end_tp_report 04:43:48 INFO - PROCESS | 1938 | __start_cc_report 04:43:48 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1998 04:43:48 INFO - PROCESS | 1938 | __end_cc_report 04:43:48 INFO - PROCESS | 1938 | __startTimestamp1468237428858__endTimestamp 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | ------- Summary: start ------- 04:43:48 INFO - PROCESS | 1938 | Number of tests: 14 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#0] ai-astar Cycles:10 Average:110.40 Median:112.00 stddev:3.81 (3.4%) stddev-sans-first:1.13 04:43:48 INFO - PROCESS | 1938 | Values: 100.0 112.0 112.0 111.0 109.0 113.0 112.0 112.0 112.0 111.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:117.50 stddev:20.16 (17.2%) stddev-sans-first:19.49 04:43:48 INFO - PROCESS | 1938 | Values: 144.0 107.0 104.0 158.0 107.0 105.0 128.0 105.0 139.0 107.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.50 stddev:2.00 (1.1%) stddev-sans-first:2.03 04:43:48 INFO - PROCESS | 1938 | Values: 185.0 185.0 183.0 182.0 182.0 180.0 181.0 186.0 184.0 185.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#3] audio-fft Cycles:10 Average:73.80 Median:68.50 stddev:18.13 (26.5%) stddev-sans-first:19.09 04:43:48 INFO - PROCESS | 1938 | Values: 80.0 73.0 57.0 109.0 58.0 57.0 64.0 63.0 99.0 78.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:87.00 stddev:12.79 (14.7%) stddev-sans-first:13.56 04:43:48 INFO - PROCESS | 1938 | Values: 82.0 93.0 92.0 72.0 70.0 68.0 97.0 70.0 68.0 98.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.65 (1.9%) stddev-sans-first:1.66 04:43:48 INFO - PROCESS | 1938 | Values: 87.0 84.0 87.0 84.0 87.0 84.0 88.0 84.0 86.0 84.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:0.71 (0.8%) stddev-sans-first:0.73 04:43:48 INFO - PROCESS | 1938 | Values: 92.0 92.0 93.0 93.0 93.0 93.0 93.0 92.0 93.0 91.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#7] imaging-desaturate Cycles:10 Average:109.40 Median:104.50 stddev:27.09 (25.9%) stddev-sans-first:27.13 04:43:48 INFO - PROCESS | 1938 | Values: 84.0 86.0 100.0 140.0 134.0 69.0 130.0 130.0 136.0 85.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.26 (2.3%) stddev-sans-first:1.20 04:43:48 INFO - PROCESS | 1938 | Values: 56.0 55.0 55.0 55.0 53.0 55.0 53.0 53.0 53.0 56.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:2.88 (4.0%) stddev-sans-first:2.22 04:43:48 INFO - PROCESS | 1938 | Values: 66.0 74.0 69.0 77.0 72.0 72.0 72.0 72.0 71.0 71.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.50 stddev:1.87 (2.2%) stddev-sans-first:1.94 04:43:48 INFO - PROCESS | 1938 | Values: 84.0 81.0 83.0 82.0 82.0 81.0 85.0 80.0 85.0 85.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:145.00 stddev:12.83 (8.9%) stddev-sans-first:12.80 04:43:48 INFO - PROCESS | 1938 | Values: 133.0 145.0 147.0 140.0 145.0 151.0 144.0 137.0 178.0 134.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:162.00 stddev:15.00 (9.3%) stddev-sans-first:10.90 04:43:48 INFO - PROCESS | 1938 | Values: 201.0 194.0 162.0 162.0 162.0 172.0 160.0 162.0 162.0 162.0 04:43:48 INFO - PROCESS | 1938 | 04:43:48 INFO - PROCESS | 1938 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 04:43:48 INFO - PROCESS | 1938 | Values: 65.0 67.0 66.0 66.0 66.0 67.0 66.0 66.0 65.0 65.0 04:43:48 INFO - PROCESS | 1938 | -------- Summary: end -------- 04:43:48 INFO - PROCESS | 1938 | 04:43:49 INFO - TEST-INFO | 1938: exit 0 04:43:49 INFO - TEST-OK | kraken | took 58934ms 04:43:49 INFO - SUITE-END | took 1174s 04:43:50 INFO - Completed test suite (00:19:35) 04:43:50 INFO - javascript benchmark 04:43:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.37999470098, 3367.8316084195785, 3382.6786455286747, 3391.6777906098914, 3438.6561343865615, 5630.09918413054, 5693.146028095783, 5755.39568345324, 5772.971135144324, 5814.156947242653, 5748.166515855209, 5780.190773291732, 5851.444112809287, 5868.589198756089, 5884.146798714184, 5146.588272938156, 5157.458466860982, 5216.087184742674, 5251.921221181686, 5349.625526213166, 5327.307450031491, 5330.387005494379, 5369.865753356178, 5386.811461598825, 5400.378956420022, 5174.896502069975, 5186.818461353834, 5224.895502089975, 5227.372715274191, 5265.736713164346, 5188.040212560688, 5193.480651934814, 5196.558292545101, 5241.554467870235, 5343.251944727741, 5218.634695571312, 5227.503387178212, 5231.989134559708, 5292.629515933886, 5302.946970530284, 5706.572007099443, 5727.513161381287, 5847.970760146214, 5888.528917686617, 5910.645361278337, 6231.189945307126, 6266.749330026839, 6312.305646378894, 6313.43179113882, 6317.968410157966, 6247.593906396115, 6247.687615619201, 6270.905936410957, 6275.96862015687, 6281.120643109968, 5752.568557358215, 5824.621399609012, 5877.500412464903, 5957.642541447527, 5972.313183983854, 6247.656378899204, 6254.593451425642, 6257.687115644199, 6257.9687101564205, 6272.937270627235, 5263.236830659533, 6201.782937597162, 6219.844503887349, 6220.440160385587, 6233.0338797486465, 6134.620279381772, 6140.6929653517145, 6181.629102253789, 6217.813465596039, 6279.591826531261, 6109.694515274218, 6187.102870083812, 6194.628322300587, 6207.906881396782, 6209.285932117774, 6180.351063138396, 6225.688715564294, 6241.188645476059, 6243.719032643542, 6272.1219029335925, 4928.186849169919, 4967.081089998362, 4974.577160941288, 4987.750612469435, 5027.296178535008, 4446.377507149002, 4542.750148741851, 4562.726236425825, 4582.9541704582525, 4585.380973568593, 5807.709614519257, 5821.592488525847, 5823.213866128019, 5844.678542680108, 5876.0, 6222.719977601018, 6275.403836635513, 6280.152179455807, 6292.811215663537, 6299.433051025429, 5695.572832037614, 5708.686022268815, 5743.62666426681, 5798.623089499169, 5801.332846722681, 5477.260569823103, 5481.972590137024, 5504.91742623861, 5510.5316048135555, 5534.1975413564805, 6310.7475700971445, 6318.557700960981, 6320.5891617044745, 6321.367863213642, 6344.873102537924, 6166.951618224823, 6202.069689546604, 6227.065940108839, 6240.594361366497, 6301.842453938688, 6287.811365659038, 6292.937070629235, 6313.40022697843, 6317.431431171217, 6324.462420694293, 6276.340984196594, 6281.780137695158, 6286.654234017173, 6286.905696414649, 6309.779157729457, 6119.204503414528, 6146.477549408351, 6227.719752611053, 6252.218472690914, 6296.433321001132, 6175.567710260419, 6181.690915454299, 6182.1345011697495, 6182.845428864314, 6204.906926396017, 6154.230721159855, 6164.568480206522, 6166.722497487534, 6179.474744646755, 6185.752569897154, 5847.94152058474, 5918.881622367615, 5937.732802023833, 5940.495057919952, 6043.09353596964, 6130.42522140693, 6142.877142457215, 6198.318184999736, 6214.098955651496, 6227.314995350417, 6034.517238620812, 6047.909281360871, 6048.4858787003595, 6053.424924632153, 6083.695815209133], "value": 5752.0505186386135, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.93695819543433, 516.9948300516995, 517.9041877252708, 530.1385248970423, 538.6256551696572, 30446.69553304469, 32128.357432851328, 32247.19382015448, 42389.7880510597, 43407.0, 44106.55893441065, 44363.0, 44544.77727611366, 44545.1090978181, 44648.553514464846, 9692.951535242331, 9891.159251463634, 9893.75265618358, 9903.95048024759, 9941.850872236922, 9697.90302096977, 9796.461194634292, 9799.461029643368, 9820.950895245549, 9850.162736167435, 9767.706968790948, 9806.9509652452, 9821.558029888673, 9823.017698230191, 9842.753931151707, 9749.220062394992, 9792.755181120458, 9801.6079356826, 9810.754731131707, 9852.802943941153, 9608.615655373776, 9682.56428460721, 9700.563474643657, 9702.320837541374, 9720.027997200294, 41964.37053444201, 42295.0, 42308.78845605783, 42363.0, 42372.78813605943, 3477.5479187705437, 3614.6023937366867, 3684.299983003221, 3743.4197699356373, 3959.6362277314065, 32070.198245043826, 33054.0, 33278.334433311196, 33388.165295867555, 33689.32621347584, 3259.282957749291, 3431.605365382963, 3442.707369873564, 3460.653934606544, 3462.567179102612, 488519.5574022107, 489953.0, 489968.0, 490175.0, 495197.0, 56019.71990140105, 63713.681431593475, 64156.71686566243, 64241.218290354096, 64246.678766606805, 66890.66218675696, 66932.0, 67016.32983670101, 67082.32917670859, 67206.99189512161, 16831.0, 17081.743773843402, 17091.48725538236, 17094.40169594058, 17118.48644540666, 13001.609951701465, 13094.345282735825, 13094.934525327313, 13107.541236056692, 13111.082224244212, 15517.45688900883, 17182.797204195587, 18262.908685456754, 18476.353327633467, 18497.0, 5243.5318110929, 6295.842603934938, 6296.905546416808, 6321.0, 6428.5178611604315, 889.74642226965, 974.605284859633, 1001.972977697851, 1013.2198207380276, 1017.4454922067491, 27430.45139097207, 29797.702022979927, 30178.396432071237, 30809.949201272946, 31163.688363116533, 1055.614700634263, 1064.99467502662, 1068.6526878764432, 1158.5655379232787, 1162.1690491298762, 15838.683226335408, 15870.302048651756, 15881.444149454714, 16210.513684589481, 16341.918290408472, 662255.0, 668096.0, 671611.0, 672424.0, 672521.0, 58321.0, 62044.0, 62310.68844655748, 62358.0, 62585.374146258866, 1271.1610337177603, 1329.2954733991, 1339.5780329196273, 1381.2679279981626, 1434.4620767212296, 26506.602400964388, 30997.0, 31585.52621710722, 31777.8411107943, 31849.737850928024, 52707.0, 53329.733351332994, 53341.733291333294, 53382.19926701025, 53434.664133396975, 501677.0, 517586.0, 517639.0, 518317.5345972389, 519515.0242487936, 28651.57022644704, 36418.45372319364, 36992.81503592465, 42270.0, 43216.56783432126, 49432.50567494279, 49471.50528494669, 49498.75250623724, 50532.0, 50548.73628159326, 39298.80350598229, 40132.1973560533, 41108.97227569335, 42829.929251768954, 44652.10695786131, 41127.0, 41240.1751964953, 41509.75470735883, 41664.0, 42728.5727142737, 40214.597854021085, 40728.77813665595, 41060.38409423801, 41216.38175427311, 41221.76334709964, 34899.825500872335, 35853.64146358503, 36242.63757362393, 36455.453168201966, 36564.634353656125, 25799.613005804553, 25821.575125079587, 25876.482470350864, 25941.740582594688, 27038.864805676632], "value": 20590.737262685238, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.3892521638429, 384.35812193636633, 391.4715134568333, 392.25471603952485, 415.05781875143424, 15492.978720235878, 17808.821911780877, 21461.892690536566, 21493.892530537345, 21507.46231344217, 20899.686504702404, 21539.353819385444, 21603.243886463977, 21617.891910540464, 21647.891760541173, 5359.705216213107, 5732.254806875121, 5847.766089356421, 5927.1998280232265, 5935.9406405935815, 5941.405859414067, 5957.136215248786, 5973.074173503092, 6012.158297838306, 6042.546808989321, 4983.0, 5877.735501902424, 5973.910391344133, 5978.432048955342, 5991.850203744898, 5731.713414329267, 5920.970395148018, 5976.0, 5998.6100903441575, 6003.30961939376, 5175.171972484384, 5201.765920533548, 5204.895902081975, 5210.843674689765, 5211.7133557654315, 25565.10522131735, 25624.743752562423, 25698.8435520402, 26099.347516312053, 26099.82550785219, 2307.2962746362355, 2394.7246066702205, 2443.218170185541, 2473.5547601431736, 2545.121932933135, 15506.61233469161, 15890.36438542457, 16370.83629163717, 16417.58956026097, 16453.58866028347, 1907.8092190780949, 1918.6834172361544, 1940.6797878350055, 1968.1635304995464, 1993.7308463357551, 115985.0, 118036.0, 118068.0, 118443.0, 122799.0, 10594.311369761095, 10840.620578279722, 11062.446877656084, 11353.148513861328, 11359.148063895242, 12675.93662031684, 13839.930800345934, 13979.930100349433, 13981.650458738613, 14081.788773168411, 8664.350173736995, 8875.423097498771, 8890.73327800167, 8929.821403572023, 8963.238124759338, 7452.739154129579, 8954.89658609519, 8976.0, 9128.726138215865, 9161.679341223158, 6752.831179220559, 6849.04113424121, 6867.862642747217, 6870.175578930561, 6882.621455819978, 1764.97352539712, 1875.2124107874724, 1895.8293753562245, 1896.5637903282322, 1918.0, 946.4132238012339, 1016.3901659004538, 1035.9119474844722, 1042.5360714482135, 1049.8740151181755, 18425.90787046083, 21966.560668786537, 23471.765282346958, 23564.41088972755, 23672.64491032636, 956.416585882611, 971.8396464583265, 1031.783325501638, 1048.3919326790444, 1058.8729352477599, 11673.88326116745, 12138.757224855453, 12404.81392779109, 12574.308413037188, 12753.936230318788, 519909.0, 587295.0, 588490.0, 601431.0, 602268.0, 83822.16177838265, 107194.0, 107431.0, 120917.39541302237, 122970.0, 999.7800483893532, 1006.8741407324085, 1021.1677482851452, 1040.8594839696543, 1042.8070806900748, 16096.597585060466, 20522.794772052686, 20612.381628551168, 20693.482662933548, 20709.58580828405, 52929.0, 53009.0, 53219.93560128698, 56268.87462250814, 57096.42903570911, 75793.0, 76300.0, 77257.76727879308, 77461.0, 77824.0, 7749.573773442515, 7782.961085194538, 8030.43786934909, 8234.0, 8376.288015518505, 13633.454661813814, 13704.10923289983, 13735.519256825963, 13980.161190328612, 13984.650383740487, 11657.650270491898, 11661.825072624088, 11808.82286765682, 11930.522779089086, 11959.461824217757, 11640.88359116398, 11649.417529123679, 11670.824937626112, 11816.881831181578, 11931.343776092399, 11706.590269340531, 11783.52865885355, 11871.643850684493, 12017.459214335551, 12123.151379403356, 8140.145284745254, 8161.0, 8214.794630134294, 8267.751967440987, 8430.83138337242, 11792.823107653221, 11796.587119451122, 11827.822582661094, 11964.880351196376, 11974.580889669169], "value": 9985.202436797566, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1565.6320764620311, 1590.5625952862965, 1825.7900341460736, 1951.190256043742, 1959.098814545309, 8550.91449085509, 8860.64557417704, 9022.684206052789, 9044.8643270351, 9046.63813447462, 7893.842123157533, 8134.308583770372, 8524.445911015795, 8736.956315218415, 8833.469991800497, 2349.3421841884256, 2385.3082606044227, 2385.7713277661933, 2387.3554140382084, 2389.3668177932864, 2108.926187583435, 2355.717313922332, 2357.834951553392, 2399.7240317363467, 2400.987995060022, 2160.2655097266925, 2323.0939933425975, 2379.5003049359657, 2388.164142550111, 2392.7009123859516, 2347.6009078456705, 2361.1499860050367, 2370.530271231847, 2373.703287089114, 2382.2376839411395, 2328.371339738278, 2334.1830359374253, 2340.157543284416, 2344.284993077111, 2357.634566642173, 8811.647534098629, 8950.925888893375, 8956.014838367775, 8957.731268061969, 8976.8653470198, 4686.953130468686, 5226.05930932432, 5392.9191062133705, 5394.568434525228, 5397.568194544427, 3470.9826450867836, 3576.6423357664285, 3630.8547658093644, 3636.8727094551564, 3638.7270954678506, 1072.9892701073045, 1088.6353071720982, 1096.6764804382647, 1109.3066833229232, 1113.6158025481195, 11655.825162622568, 11728.94135529317, 11732.413379331, 11736.471858766376, 11749.530018799238, 4096.566201829395, 4507.211238033331, 4628.560286772751, 4770.522947705271, 4792.664513484023, 9907.0, 9946.055124763136, 9957.55191016406, 9966.302358834957, 9993.500324983721, 5344.305240318734, 5349.983503134392, 5353.785848566014, 5354.0, 5361.222622719683, 4724.95275047252, 4896.167651499253, 4896.1921282987905, 4898.755062246944, 4922.335484709519, 3785.640364165455, 3800.9429858552144, 3816.8282427290283, 3866.5360156781367, 3892.0853599404118, 1107.0755918807724, 1112.6161474291357, 1114.8272017837248, 1174.3188950408742, 1177.652592485219, 713.6717110129294, 895.0, 923.1922068190333, 944.4710961861242, 987.980240395188, 5820.505257053113, 5901.645901245939, 5934.902043122036, 5962.940370596325, 6030.698465076729, 924.4961495984704, 940.586142097475, 946.4888959961681, 947.3321308477507, 976.6630512473185, 3855.2867719471988, 3933.8033098344968, 3988.12261302513, 4005.1589166274825, 4073.816678249427, 10490.31812932157, 10820.783584328427, 10969.067629251604, 10976.286541374944, 10992.505337259843, 8776.912230877737, 10913.563457461612, 10916.836247456295, 10950.233483656228, 10960.0, 7938.841223175619, 8220.753377398687, 8292.460990035628, 8384.203500667427, 8415.410921235454, 5991.400859913973, 6019.488343490677, 6023.216981792339, 6196.597221180609, 6271.937280627135, 9982.251331150048, 9986.400815951067, 10100.747481313027, 10123.7469063274, 10127.392356458637, 10360.792784144425, 10381.377117372982, 10395.064444200058, 10395.272330937065, 10398.792024159626, 6947.3747362737595, 6960.443164546723, 7004.929950700428, 7018.2630823762765, 7316.853662926818, 8396.87404688918, 8429.831403371776, 8505.277051450448, 8512.489250644981, 8530.872036919576, 6267.749290028348, 7432.777016689508, 7572.810679733051, 7619.1999840016, 7719.614019299125, 7345.6694448751, 7442.590657513889, 7481.663325150273, 7552.0, 7562.584057876649, 7112.466565007541, 7133.072700548895, 7180.533265337736, 7182.784516464514, 7203.747868824566, 4928.654994150375, 5104.1067813133295, 5106.719130447861, 5140.228965655182, 5191.974040129775, 4981.0, 4995.950040499694, 5059.772310246121, 5091.821786237464, 5189.896202076013], "value": 4606.990593613275, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.55900621118013, 317.24179211684077, 321.50969771099074, 323.3952508808528, 328.6040321412698, 4595.73617255255, 4840.80636774529, 5473.644213126149, 5600.907822974522, 5623.26897503325, 4875.780589873455, 5502.889942201154, 5518.0, 5588.804391846286, 5603.831885043445, 3500.702440292578, 3967.9404808927816, 4159.230542349675, 4220.472440944882, 4251.341042138473, 3496.199457279503, 4109.602735070075, 4200.390943313218, 4219.303814870543, 4240.533541310453, 3522.4716292556195, 3667.44988251763, 4154.459920210368, 4181.435506206669, 4187.120704652027, 3300.9766972238563, 4112.3420252759715, 4119.855805046839, 4161.417401563784, 4184.14225083857, 3022.9395412091853, 3348.5479460272795, 3578.35589593873, 3612.710983121344, 3648.434492653643, 4513.548645135493, 5058.721770302632, 5233.738313084331, 5238.319018527605, 5337.277988226033, 4338.132373525276, 4354.629856462205, 4856.757162141878, 4890.899547601797, 4890.902181956341, 5130.358705161855, 5318.388385335697, 5344.759485823147, 5789.102689083199, 5810.622309549908, 4058.15354013923, 4063.89840253996, 4103.938440923389, 4106.77412742299, 4126.154138401621, 4711.316859055418, 5143.897122057575, 5797.73910174039, 5894.764209431618, 6065.575409721366, 3672.926541469209, 3941.6452519273403, 4072.9592704073175, 4117.711760176759, 4123.299039163349, 4194.42708984131, 4211.115665710174, 4211.7996371034105, 4222.767747773902, 4230.513490948519, 4475.239209334421, 4798.568128868418, 4803.327534145223, 4843.95156048435, 4883.533815423189, 5797.36229014808, 5833.941660583425, 5841.824745257649, 5858.707064646751, 5871.55963302754, 1366.4124426496503, 1387.840398354182, 1393.7560926837764, 1396.9720605587831, 1399.5794268817158, 790.4743345675176, 835.2023817254508, 843.6627944707584, 845.2815107158866, 847.9576021198916, 5190.0, 5195.948040519546, 5204.609654275944, 5212.765425555859, 5314.893702125936, 5057.494250574987, 5079.796808127633, 5551.472610102034, 5953.642781433128, 5973.551983601247, 4387.714798538085, 5376.731163441812, 5442.183672449085, 5460.0, 5486.31421072366, 2768.764655004307, 2993.3414648777234, 3018.517037274026, 3137.9215519612194, 3149.7952633078776, 5887.852803679943, 6097.512199024068, 6122.724477398528, 6180.165677633552, 6186.226721659793, 5771.249737534094, 5962.701864906738, 6019.605687847896, 6075.939240607538, 6157.0, 5129.794808207705, 5640.802571909963, 5978.850528736816, 6014.398560143951, 6102.91802639673, 5058.974705126451, 5720.0, 5963.791267305797, 6000.309964354155, 6034.939650603438, 4981.825636102719, 5858.560607954506, 5907.675077870586, 5923.585349025527, 5923.9420996617455, 5912.314990227765, 6161.507079433724, 6170.074488826533, 6173.506119510339, 6225.536998805186, 3703.185299234164, 4025.852631999866, 4030.3551431770775, 4056.9188616228103, 4059.6346328830546, 6041.979010494752, 6054.848628784315, 6066.575339726177, 6067.726952287069, 6072.696365181635, 5064.328771505321, 5119.974400128124, 5202.907389448107, 5459.645123066988, 5912.526997840248, 5299.913517728934, 5390.460953904578, 5521.779128834802, 5727.706568722918, 5807.535397168132, 4770.976145119253, 5443.401225865073, 5688.488036076773, 5697.57268204878, 5751.597388182954, 5536.117719975227, 5636.196417146473, 5753.9136912945505, 5851.297844258716, 5881.588288819741, 5225.973870130625, 5624.971875140598, 5668.2064510967675, 5686.545076393796, 5696.914546281892], "value": 4213.544280337739, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.5265906149596, 777.9730755402867, 813.9215539410283, 852.9744107676768, 857.1899554920599, 17067.0, 18473.35343262986, 18560.814391856078, 18573.97843118628, 18578.885266883997, 17253.396131135414, 17399.130043497807, 17427.82572174281, 17444.30222791087, 17501.91249043753, 738.9002484664583, 911.4759013567192, 929.326238477102, 932.0818993291603, 933.8225737109962, 744.04761904762, 914.063085337531, 938.1837801113041, 942.3309450290302, 945.0691069296737, 969.9515024248794, 978.9510524473783, 979.1383582447436, 982.1111893736145, 982.6757170133839, 762.161622215564, 787.46845879032, 969.4571040217465, 971.6162115964155, 976.6581696406237, 578.9826305210851, 582.2954225387286, 611.871506983534, 719.2196466833492, 720.9026781384498, 15646.608834779221, 15704.0, 15704.272530021803, 15722.135282559455, 15829.841701582953, 13534.120282181726, 13658.795118073236, 13785.379657915419, 13983.300834958312, 13997.580072597839, 10809.567617295299, 12634.936825315906, 13107.410166542433, 13269.668258293523, 13601.659958501017, 3924.2740093082584, 4590.74750888701, 4591.494935557086, 4730.526947305277, 4781.713097214179, 92915.0, 97745.5112724439, 108316.0, 109873.45063274633, 110034.9999999992, 10520.894791052144, 10704.732381690364, 10752.569897204025, 10808.675739727822, 11111.888881111247, 22498.88750556237, 22616.208432704778, 22629.321120366414, 22642.886785566297, 22905.08379664828, 8156.959215204005, 8339.708110216114, 8450.112738162527, 8503.319734421231, 8578.785530361665, 4044.292248856438, 4602.884927876763, 4616.446026476844, 4699.0, 4802.879928001758, 3541.451075083366, 3709.5919448860604, 3717.5910649828497, 3717.5910649828497, 3720.479132921393, 558.4471373340364, 623.2365352443311, 629.2511910826103, 639.191422850095, 646.544186348623, 531.9069610216578, 546.5955193157115, 563.5181919458886, 636.726207730671, 706.2478460439615, 9607.951960240294, 9650.806983860284, 9731.270154738422, 9770.41377517337, 9833.508324583741, 595.3778301674761, 624.2041397218492, 635.9745610175634, 654.3881470824793, 688.102026854959, 4468.128714900563, 4559.316102584638, 4722.150012997693, 4737.242041273448, 4794.904101918012, 92065.0, 123904.0, 138155.0, 145089.0, 146687.0, 125064.0, 130473.0, 131682.0, 133706.0, 135665.0, 101313.49343253384, 102938.0, 110202.44898775454, 110359.89640103657, 110774.0, 9086.500242486727, 9105.772355690895, 9139.77150571241, 9161.221296189899, 9289.907100928904, 44234.11531769411, 56593.71703141458, 57304.71347643235, 57842.7107864458, 58341.70829145827, 87226.0, 91260.0, 91498.0, 91917.54041230018, 93106.53446732994, 11710.414479275832, 11845.170838041255, 11921.761564768483, 12281.0, 12401.193922395016, 12707.93646031764, 13163.60509184726, 13194.736105277649, 13278.601641950758, 13369.866301336862, 11768.293902365886, 12408.193467424588, 12443.377831108228, 12524.185927914656, 12667.80998285008, 11779.52881884715, 11853.644390668294, 12105.576304829288, 12231.0, 12271.263724176579, 12102.818825282504, 12200.938995304967, 12209.816852747039, 12231.755364892475, 12340.81488777651, 9136.219261271763, 10457.529411176667, 10637.0, 10894.782104358026, 11062.612808551663, 10340.37957722539, 10728.785424291627, 10755.300905441121, 10759.946200268949, 10791.244612877023], "value": 7826.463388218116, "unit": "score"}], "name": "dromaeo_css", "value": 7511.984022772317, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 112.0, 111.0, 109.0, 113.0, 112.0, 112.0, 112.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 104.0, 158.0, 107.0, 105.0, 128.0, 105.0, 139.0, 107.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 182.0, 182.0, 180.0, 181.0, 186.0, 184.0, 185.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 57.0, 109.0, 58.0, 57.0, 64.0, 63.0, 99.0, 78.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 93.0, 92.0, 72.0, 70.0, 68.0, 97.0, 70.0, 68.0, 98.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 84.0, 88.0, 84.0, 86.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0, 93.0, 91.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 100.0, 140.0, 134.0, 69.0, 130.0, 130.0, 136.0, 85.0], "value": 109.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 55.0, 53.0, 55.0, 53.0, 53.0, 53.0, 56.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 69.0, 77.0, 72.0, 72.0, 72.0, 72.0, 71.0, 71.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 83.0, 82.0, 82.0, 81.0, 85.0, 80.0, 85.0, 85.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 145.0, 147.0, 140.0, 145.0, 151.0, 144.0, 137.0, 178.0, 134.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 194.0, 162.0, 162.0, 162.0, 172.0, 160.0, 162.0, 162.0, 162.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 65.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1446.3000000000004, "alertThreshold": 2.0}]} 04:43:50 INFO - Return code: 0 04:43:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:43:50 INFO - # TBPL SUCCESS # 04:43:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:43:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:43:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:43:50 INFO - 2016-07-11 04:43:50.158 screenresolution[1942:14918] starting screenresolution argv=screenresolution get 04:43:50 INFO - 2016-07-11 04:43:50.161 screenresolution[1942:14918] Display 0: 1600x1200x32@60 04:43:50 INFO - 2016-07-11 04:43:50.167 screenresolution[1943:14922] starting screenresolution argv=screenresolution list 04:43:50 INFO - Available Modes on Display 0 04:43:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:43:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:43:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:43:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:43:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:43:50 INFO - Intel Iris: 04:43:50 INFO - Chipset Model: Intel Iris 04:43:50 INFO - Type: GPU 04:43:50 INFO - Bus: Built-In 04:43:50 INFO - VRAM (Dynamic, Max): 1536 MB 04:43:50 INFO - Vendor: Intel (0x8086) 04:43:50 INFO - Device ID: 0x0a2e 04:43:50 INFO - Revision ID: 0x0009 04:43:50 INFO - Displays: 04:43:50 INFO - Display: 04:43:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:43:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:43:50 INFO - Display Serial Number: 1600x1200 60 04:43:50 INFO - Main Display: Yes 04:43:50 INFO - Mirror: Off 04:43:50 INFO - Online: Yes 04:43:50 INFO - Rotation: Supported 04:43:50 INFO - Return code: 0 04:43:50 INFO - Running post-action listener: _resource_record_post_action 04:43:50 INFO - ##### 04:43:50 INFO - ##### Finished run-tests step (success) 04:43:50 INFO - ##### 04:43:50 INFO - Running post-run listener: _resource_record_post_run 04:43:50 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 1024000; Write bytes: 703624704; Read time: 10; Write time: 1943 04:43:50 INFO - TinderboxPrint: CPU usage
53.6% 04:43:50 INFO - TinderboxPrint: I/O read bytes / time
1,024,000 / 10 04:43:50 INFO - TinderboxPrint: I/O write bytes / time
703,624,704 / 1,943 04:43:50 INFO - TinderboxPrint: CPU idle
2,248.6 (46.4%) 04:43:50 INFO - TinderboxPrint: CPU system
968.2 (20.0%) 04:43:50 INFO - TinderboxPrint: CPU user
1,624.9 (33.6%) 04:43:50 INFO - TinderboxPrint: Swap in / out
407,515,136 / 0 04:43:50 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397761024; Write bytes: 392947712; Read time: 29527; Write time: 823 04:43:50 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1024000; Write bytes: 293023232; Read time: 10; Write time: 1073 04:43:50 INFO - Running post-run listener: _upload_blobber_files 04:43:50 INFO - Blob upload gear active. 04:43:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:43:50 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:43:50 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:43:50 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:43:51 INFO - (blobuploader) - INFO - Open directory for files ... 04:43:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:43:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:43:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:52 INFO - (blobuploader) - INFO - Done attempting. 04:43:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:43:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:43:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:53 INFO - (blobuploader) - INFO - Done attempting. 04:43:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:43:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:43:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:55 INFO - (blobuploader) - INFO - Done attempting. 04:43:55 INFO - (blobuploader) - INFO - Iteration through files over. 04:43:55 INFO - Return code: 0 04:43:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:43:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:43:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/16190be44e0732fdeb44b9d7bb3ef60e07324738c44460b4a756a5d56303d7831d1e9b39283e41d082f90468da71e216f0a7b7b981bdd1fe1f3cbdfb06ac3632", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbd5c4e80917eaa9c31f80b58a0cb54ed9fd4061620b697ed5d5ffa1249052fa1c9f40e89e8ff4e2b837712588eabded4283db3437e6e9e7e030fe6914d3bb3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd8f28f784f4065172858683d3d0c222d6c47b25269f79998858d3cc4be0a60e6e0fc025c12186a708ee7696d34d603da302ffae2d9a85c9845f12fc50212dcc"} 04:43:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:43:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:43:55 INFO - Contents: 04:43:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/16190be44e0732fdeb44b9d7bb3ef60e07324738c44460b4a756a5d56303d7831d1e9b39283e41d082f90468da71e216f0a7b7b981bdd1fe1f3cbdfb06ac3632", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbd5c4e80917eaa9c31f80b58a0cb54ed9fd4061620b697ed5d5ffa1249052fa1c9f40e89e8ff4e2b837712588eabded4283db3437e6e9e7e030fe6914d3bb3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd8f28f784f4065172858683d3d0c222d6c47b25269f79998858d3cc4be0a60e6e0fc025c12186a708ee7696d34d603da302ffae2d9a85c9845f12fc50212dcc"} 04:43:55 INFO - Running post-run listener: copy_logs_to_upload_dir 04:43:55 INFO - Copying logs to upload dir... 04:43:55 INFO - mkdir: /builds/slave/test/build/upload/logs 04:43:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.885689 ========= master_lag: 1.48 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-11 04:43:56.738454) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 04:43:56.746285) ========= 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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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/16190be44e0732fdeb44b9d7bb3ef60e07324738c44460b4a756a5d56303d7831d1e9b39283e41d082f90468da71e216f0a7b7b981bdd1fe1f3cbdfb06ac3632", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbd5c4e80917eaa9c31f80b58a0cb54ed9fd4061620b697ed5d5ffa1249052fa1c9f40e89e8ff4e2b837712588eabded4283db3437e6e9e7e030fe6914d3bb3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd8f28f784f4065172858683d3d0c222d6c47b25269f79998858d3cc4be0a60e6e0fc025c12186a708ee7696d34d603da302ffae2d9a85c9845f12fc50212dcc"} build_url:https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010888 build_url: 'https://queue.taskcluster.net/v1/task/aJzPUfFbQMK3hYXiHsaDag/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/16190be44e0732fdeb44b9d7bb3ef60e07324738c44460b4a756a5d56303d7831d1e9b39283e41d082f90468da71e216f0a7b7b981bdd1fe1f3cbdfb06ac3632", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbd5c4e80917eaa9c31f80b58a0cb54ed9fd4061620b697ed5d5ffa1249052fa1c9f40e89e8ff4e2b837712588eabded4283db3437e6e9e7e030fe6914d3bb3f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd8f28f784f4065172858683d3d0c222d6c47b25269f79998858d3cc4be0a60e6e0fc025c12186a708ee7696d34d603da302ffae2d9a85c9845f12fc50212dcc"}' ========= master_lag: 0.54 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 04:43:57.295609) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 04:43:57.301005) ========= 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.bA8jGT8hIq/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.sxPpDCgZDh/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.004774 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 04:43:57.323444) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 04:43:57.325779) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 04:43:57.326101) ========= ========= Total master_lag: 2.10 =========