builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0164 starttime: 1468946368.86 results: success (0) buildid: 20160719072820 builduid: d154aa9f687f457f9fe02d9ab062e41c revision: c0ab769be276bf670f47d77ab470708beba15488 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:28.863536) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:28.864049) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:28.864380) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:28.886754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:28.887080) ========= 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.gkzy68R1dc/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.7slfFytNUD/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-19 09:39:28-- 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-19 09:39:29 (8.58 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135457 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:29.044134) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:29.044442) ========= 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.gkzy68R1dc/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.7slfFytNUD/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.020704 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:29.093303) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 09:39:29.093766) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c0ab769be276bf670f47d77ab470708beba15488 --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 c0ab769be276bf670f47d77ab470708beba15488 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gkzy68R1dc/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.7slfFytNUD/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-19 09:39:29,171 truncating revision to first 12 chars 2016-07-19 09:39:29,171 Setting DEBUG logging. 2016-07-19 09:39:29,171 attempt 1/10 2016-07-19 09:39:29,171 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c0ab769be276?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 09:39:29,952 unpacking tar archive at: fx-team-c0ab769be276/testing/mozharness/ program finished with exit code 0 elapsedTime=1.055734 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 09:39:30.170356) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:30.170776) ========= script_repo_revision: c0ab769be276bf670f47d77ab470708beba15488 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:30.171201) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:30.171468) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 09:39:30.189055) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-19 09:39:30.189344) ========= /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.gkzy68R1dc/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.7slfFytNUD/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:39:30 INFO - MultiFileLogger online at 20160719 09:39:30 in /builds/slave/test 09:39:30 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 09:39:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:39:30 INFO - {'append_to_log': False, 09:39:30 INFO - 'base_work_dir': '/builds/slave/test', 09:39:30 INFO - 'blob_upload_branch': 'Fx-Team', 09:39:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:39:30 INFO - 'branch': 'Fx-Team', 09:39:30 INFO - 'buildbot_json_path': 'buildprops.json', 09:39:30 INFO - 'config_files': ('talos/mac_config.py',), 09:39:30 INFO - 'default_actions': ('clobber', 09:39:30 INFO - 'read-buildbot-config', 09:39:30 INFO - 'download-and-extract', 09:39:30 INFO - 'populate-webroot', 09:39:30 INFO - 'create-virtualenv', 09:39:30 INFO - 'install', 09:39:30 INFO - 'run-tests'), 09:39:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:39:30 INFO - 'download_minidump_stackwalk': True, 09:39:30 INFO - 'download_symbols': 'ondemand', 09:39:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:39:30 INFO - 'tooltool.py': '/tools/tooltool.py', 09:39:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:39:30 INFO - '/tools/misc-python/virtualenv.py')}, 09:39:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:39:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:39:30 INFO - 'installer_path': 'installer.exe', 09:39:30 INFO - 'log_level': 'info', 09:39:30 INFO - 'log_name': 'talos', 09:39:30 INFO - 'log_to_console': True, 09:39:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:39:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:39:30 INFO - 'opt_config_files': (), 09:39:30 INFO - 'pip_index': False, 09:39:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:30 INFO - 'cmd': ('bash', 09:39:30 INFO - '-c', 09:39:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:30 INFO - 'enabled': True, 09:39:30 INFO - 'halt_on_failure': False, 09:39:30 INFO - 'name': 'check_screen_resolution'},), 09:39:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:30 INFO - 'cmd': ('bash', 09:39:30 INFO - '-c', 09:39:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:30 INFO - 'enabled': True, 09:39:30 INFO - 'halt_on_failure': False, 09:39:30 INFO - 'name': 'check_screen_resolution'},), 09:39:30 INFO - 'run_cmd_checks_enabled': True, 09:39:30 INFO - 'sps_profile': False, 09:39:30 INFO - 'sps_profile_interval': 0, 09:39:30 INFO - 'suite': 'dromaeojs', 09:39:30 INFO - 'system_bits': '32', 09:39:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:39:30 INFO - 'title': 't-yosemite-r7-0164', 09:39:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:39:30 INFO - 'use_talos_json': True, 09:39:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:39:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:39:30 INFO - 'work_dir': 'build'} 09:39:30 INFO - ##### 09:39:30 INFO - ##### Running clobber step. 09:39:30 INFO - ##### 09:39:30 INFO - Running pre-action listener: _resource_record_pre_action 09:39:30 INFO - Running main action method: clobber 09:39:30 INFO - rmtree: /builds/slave/test/build 09:39:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:39:30 INFO - Running post-action listener: _resource_record_post_action 09:39:30 INFO - ##### 09:39:30 INFO - ##### Finished clobber step (success) 09:39:30 INFO - ##### 09:39:30 INFO - ##### 09:39:30 INFO - ##### Running read-buildbot-config step. 09:39:30 INFO - ##### 09:39:30 INFO - Running pre-action listener: _resource_record_pre_action 09:39:30 INFO - Running main action method: read_buildbot_config 09:39:30 INFO - Using buildbot properties: 09:39:30 INFO - { 09:39:30 INFO - "project": "", 09:39:30 INFO - "product": "firefox", 09:39:30 INFO - "script_repo_revision": "production", 09:39:30 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:39:30 INFO - "repository": "", 09:39:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:39:30 INFO - "buildid": "20160719072820", 09:39:30 INFO - "pgo_build": "False", 09:39:30 INFO - "basedir": "/builds/slave/test", 09:39:30 INFO - "buildnumber": 96, 09:39:30 INFO - "slavename": "t-yosemite-r7-0164", 09:39:30 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:39:30 INFO - "platform": "yosemite_r7", 09:39:30 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 09:39:30 INFO - "branch": "fx-team", 09:39:30 INFO - "repo_path": "integration/fx-team", 09:39:30 INFO - "revision": "c0ab769be276bf670f47d77ab470708beba15488", 09:39:30 INFO - "stage_platform": "macosx64", 09:39:30 INFO - "builduid": "d154aa9f687f457f9fe02d9ab062e41c", 09:39:30 INFO - "slavebuilddir": "test" 09:39:30 INFO - } 09:39:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:39:30 INFO - Running post-action listener: _resource_record_post_action 09:39:30 INFO - ##### 09:39:30 INFO - ##### Finished read-buildbot-config step (success) 09:39:30 INFO - ##### 09:39:30 INFO - ##### 09:39:30 INFO - ##### Running download-and-extract step. 09:39:30 INFO - ##### 09:39:30 INFO - Running pre-action listener: _resource_record_pre_action 09:39:30 INFO - Running main action method: download_and_extract 09:39:30 INFO - mkdir: /builds/slave/test/build/tests 09:39:30 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')]} 09:39:30 INFO - https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:39:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:39:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:39:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/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 09:39:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/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 09:39:32 INFO - Downloaded 1199 bytes. 09:39:32 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:39:32 INFO - Using the following test package requirements: 09:39:32 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:39:32 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:39:32 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:39:32 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:39:32 INFO - u'jsshell-mac.zip'], 09:39:32 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:39:32 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:39:32 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:39:32 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:39:32 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:39:32 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:39:32 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:39:32 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')]} 09:39:32 INFO - https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:39:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:39:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:39:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/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 09:39:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/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 09:39:33 INFO - Downloaded 18835368 bytes. 09:39:33 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'] 09:39:33 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 09:39:37 INFO - Return code: 0 09:39:37 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 09:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:37 INFO - https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:39:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:39:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:39:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/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 09:39:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/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 09:39:38 INFO - Downloaded 18835368 bytes. 09:39:38 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'] 09:39:38 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 09:39:42 INFO - Return code: 0 09:39:42 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')]} 09:39:42 INFO - https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:39:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:39:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:39:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/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 09:39:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/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 09:39:43 INFO - Downloaded 14403317 bytes. 09:39:43 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'] 09:39:43 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 09:39:43 INFO - Return code: 0 09:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:43 INFO - https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:39:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:39:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:39:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:39:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/d3Kw9gItRrukvaJFSoctow/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 09:39:47 INFO - Downloaded 112870965 bytes. 09:39:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:39:47 INFO - mkdir: /builds/slave/test/properties 09:39:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:39:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:39:47 INFO - Contents: 09:39:47 INFO - build_url:https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:39:47 INFO - Running post-action listener: _resource_record_post_action 09:39:47 INFO - Running post-action listener: set_extra_try_arguments 09:39:47 INFO - ##### 09:39:47 INFO - ##### Finished download-and-extract step (success) 09:39:47 INFO - ##### 09:39:47 INFO - ##### 09:39:47 INFO - ##### Running populate-webroot step. 09:39:47 INFO - ##### 09:39:47 INFO - Running pre-action listener: _resource_record_pre_action 09:39:47 INFO - Running main action method: populate_webroot 09:39:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:39:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:39:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:39:47 INFO - '--tppagecycles', 09:39:47 INFO - '7'], 09:39:47 INFO - 'tests': ['tsvgm']}}, 09:39:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:39:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:39:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:39:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:39:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['damp', 'tps']}, 09:39:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'tests': ['damp', 'tps']}, 09:39:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['dromaeo_dom']}, 09:39:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:39:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['basic_compositor_video']}, 09:39:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:39:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['tsvgx', 09:39:47 INFO - 'tsvgr_opacity', 09:39:47 INFO - 'tart', 09:39:47 INFO - 'tscrollx', 09:39:47 INFO - 'cart']}, 09:39:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:39:47 INFO - 'tsvgr_opacity', 09:39:47 INFO - 'tart', 09:39:47 INFO - 'tscrollx', 09:39:47 INFO - 'cart']}, 09:39:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['tp5o']}, 09:39:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'tests': ['tp5o']}, 09:39:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--disable-e10s', 09:39:47 INFO - '--xperf_path', 09:39:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:47 INFO - 'tests': ['tp5n']}, 09:39:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--xperf_path', 09:39:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:47 INFO - 'tests': ['tp5n']}}, 09:39:47 INFO - 'talos.zip': {'path': '', 09:39:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:39:47 INFO - Running post-action listener: _resource_record_post_action 09:39:47 INFO - ##### 09:39:47 INFO - ##### Finished populate-webroot step (success) 09:39:47 INFO - ##### 09:39:47 INFO - ##### 09:39:47 INFO - ##### Running create-virtualenv step. 09:39:47 INFO - ##### 09:39:47 INFO - Running pre-action listener: _resource_record_pre_action 09:39:47 INFO - Running main action method: create_virtualenv 09:39:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:39:47 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 09:39:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:39:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:39:48 INFO - Using real prefix '/tools/python27' 09:39:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:39:48 INFO - Installing distribute.............................................................................................................................................................................................done. 09:39:51 INFO - Installing pip.................done. 09:39:51 INFO - Return code: 0 09:39:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:39:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ada44bc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, '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.gkzy68R1dc/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.7slfFytNUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:39:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:39:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:39:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:39:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:51 INFO - 'HOME': '/Users/cltbld', 09:39:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:51 INFO - 'LOGNAME': 'cltbld', 09:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:51 INFO - 'MOZ_NO_REMOTE': '1', 09:39:51 INFO - 'NO_EM_RESTART': '1', 09:39:51 INFO - 'PAGER': '/bin/cat', 09:39:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:51 INFO - 'PWD': '/builds/slave/test', 09:39:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:51 INFO - 'SHELL': '/bin/bash', 09:39:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:39:51 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:39:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:51 INFO - 'USER': 'cltbld', 09:39:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:51 INFO - 'XPC_FLAGS': '0x0', 09:39:51 INFO - 'XPC_SERVICE_NAME': '0', 09:39:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:51 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 09:39:51 INFO - Cleaning up... 09:39:51 INFO - Return code: 0 09:39:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:39:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ada44bc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, '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.gkzy68R1dc/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.7slfFytNUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:39:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:39:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:39:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:39:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:51 INFO - 'HOME': '/Users/cltbld', 09:39:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:51 INFO - 'LOGNAME': 'cltbld', 09:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:51 INFO - 'MOZ_NO_REMOTE': '1', 09:39:51 INFO - 'NO_EM_RESTART': '1', 09:39:51 INFO - 'PAGER': '/bin/cat', 09:39:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:51 INFO - 'PWD': '/builds/slave/test', 09:39:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:51 INFO - 'SHELL': '/bin/bash', 09:39:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:39:51 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:39:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:51 INFO - 'USER': 'cltbld', 09:39:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:51 INFO - 'XPC_FLAGS': '0x0', 09:39:51 INFO - 'XPC_SERVICE_NAME': '0', 09:39:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:52 INFO - Downloading/unpacking psutil>=3.1.1 09:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:39:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:55 INFO - Installing collected packages: psutil 09:39:55 INFO - Running setup.py install for psutil 09:39:55 INFO - building 'psutil._psutil_osx' extension 09:39:55 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 09:39:55 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 09:39:55 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 09:39:55 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 09:39:55 INFO - building 'psutil._psutil_posix' extension 09:39:55 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 09:39:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:39:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:39:55 INFO - ^ 09:39:55 INFO - 1 warning generated. 09:39:55 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 09:39:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:55 INFO - Successfully installed psutil 09:39:55 INFO - Cleaning up... 09:39:55 INFO - Return code: 0 09:39:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ada44bc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, '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.gkzy68R1dc/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.7slfFytNUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:39:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:39:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:39:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:39:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:55 INFO - 'HOME': '/Users/cltbld', 09:39:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:55 INFO - 'LOGNAME': 'cltbld', 09:39:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:55 INFO - 'MOZ_NO_REMOTE': '1', 09:39:55 INFO - 'NO_EM_RESTART': '1', 09:39:55 INFO - 'PAGER': '/bin/cat', 09:39:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:55 INFO - 'PWD': '/builds/slave/test', 09:39:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:55 INFO - 'SHELL': '/bin/bash', 09:39:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:39:55 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:39:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:55 INFO - 'USER': 'cltbld', 09:39:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:55 INFO - 'XPC_FLAGS': '0x0', 09:39:55 INFO - 'XPC_SERVICE_NAME': '0', 09:39:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:39:58 INFO - Installing collected packages: mozsystemmonitor 09:39:58 INFO - Running setup.py install for mozsystemmonitor 09:39:58 INFO - Successfully installed mozsystemmonitor 09:39:58 INFO - Cleaning up... 09:39:59 INFO - Return code: 0 09:39:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ada44bc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, '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.gkzy68R1dc/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.7slfFytNUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:39:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:39:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:39:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:39:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:59 INFO - 'HOME': '/Users/cltbld', 09:39:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:59 INFO - 'LOGNAME': 'cltbld', 09:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:59 INFO - 'MOZ_NO_REMOTE': '1', 09:39:59 INFO - 'NO_EM_RESTART': '1', 09:39:59 INFO - 'PAGER': '/bin/cat', 09:39:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:59 INFO - 'PWD': '/builds/slave/test', 09:39:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:59 INFO - 'SHELL': '/bin/bash', 09:39:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:39:59 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:39:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:59 INFO - 'USER': 'cltbld', 09:39:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:59 INFO - 'XPC_FLAGS': '0x0', 09:39:59 INFO - 'XPC_SERVICE_NAME': '0', 09:39:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:59 INFO - Downloading/unpacking jsonschema==2.5.1 09:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:40:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:02 INFO - Downloading functools32-3.2.3-2.tar.gz 09:40:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:40:02 INFO - warning: no files found matching '*.txt' 09:40:02 INFO - no previously-included directories found matching 'build' 09:40:02 INFO - no previously-included directories found matching 'dist' 09:40:02 INFO - no previously-included directories found matching '.git*' 09:40:02 INFO - Installing collected packages: jsonschema, functools32 09:40:02 INFO - Running setup.py install for functools32 09:40:02 INFO - warning: no files found matching '*.txt' 09:40:02 INFO - no previously-included directories found matching 'build' 09:40:02 INFO - no previously-included directories found matching 'dist' 09:40:02 INFO - no previously-included directories found matching '.git*' 09:40:02 INFO - Successfully installed jsonschema functools32 09:40:02 INFO - Cleaning up... 09:40:02 INFO - Return code: 0 09:40:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ada44bc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, '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.gkzy68R1dc/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.7slfFytNUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:40:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:40:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:40:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:40:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:02 INFO - 'HOME': '/Users/cltbld', 09:40:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:02 INFO - 'LOGNAME': 'cltbld', 09:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:02 INFO - 'MOZ_NO_REMOTE': '1', 09:40:02 INFO - 'NO_EM_RESTART': '1', 09:40:02 INFO - 'PAGER': '/bin/cat', 09:40:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:02 INFO - 'PWD': '/builds/slave/test', 09:40:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:02 INFO - 'SHELL': '/bin/bash', 09:40:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:40:02 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:40:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:02 INFO - 'USER': 'cltbld', 09:40:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:02 INFO - 'XPC_FLAGS': '0x0', 09:40:02 INFO - 'XPC_SERVICE_NAME': '0', 09:40:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:02 INFO - Downloading/unpacking blobuploader==1.2.4 09:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:05 INFO - Downloading blobuploader-1.2.4.tar.gz 09:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:40:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:40:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:06 INFO - Downloading docopt-0.6.1.tar.gz 09:40:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:40:06 INFO - Installing collected packages: blobuploader, requests, docopt 09:40:06 INFO - Running setup.py install for blobuploader 09:40:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:40:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:40:06 INFO - Running setup.py install for requests 09:40:07 INFO - Running setup.py install for docopt 09:40:07 INFO - Successfully installed blobuploader requests docopt 09:40:07 INFO - Cleaning up... 09:40:07 INFO - Return code: 0 09:40:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:07 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 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ada44bc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, '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.gkzy68R1dc/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.7slfFytNUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:40:07 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 09:40:07 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 09:40:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:40:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:07 INFO - 'HOME': '/Users/cltbld', 09:40:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:07 INFO - 'LOGNAME': 'cltbld', 09:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:07 INFO - 'MOZ_NO_REMOTE': '1', 09:40:07 INFO - 'NO_EM_RESTART': '1', 09:40:07 INFO - 'PAGER': '/bin/cat', 09:40:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:07 INFO - 'PWD': '/builds/slave/test', 09:40:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:07 INFO - 'SHELL': '/bin/bash', 09:40:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:40:07 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:40:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:07 INFO - 'USER': 'cltbld', 09:40:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:07 INFO - 'XPC_FLAGS': '0x0', 09:40:07 INFO - 'XPC_SERVICE_NAME': '0', 09:40:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:07 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-JUuD89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:07 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-jV3pM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:07 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vR5wUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:07 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-7aztVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:08 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-DBLvOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:08 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-wr66z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:40:08 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-lvVfzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:40:08 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Uzd6Cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:40:08 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-8eMvjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:40:08 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Vz6ttj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:40:08 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-YqQs_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:40:08 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-LLMtnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:40:08 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-W61_4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:40:09 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_X6SIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:09 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ThgqiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:40:09 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ZqSZaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:40:09 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-wHHxoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:40:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:40:09 INFO - Running setup.py install for manifestparser 09:40:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:40:09 INFO - Running setup.py install for mozcrash 09:40:09 INFO - Running setup.py install for mozdebug 09:40:09 INFO - Running setup.py install for mozdevice 09:40:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:40:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:40:10 INFO - Running setup.py install for mozfile 09:40:10 INFO - Running setup.py install for mozhttpd 09:40:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:40:10 INFO - Running setup.py install for mozinfo 09:40:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:40:10 INFO - Running setup.py install for mozInstall 09:40:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:40:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:40:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:40:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:40:10 INFO - Running setup.py install for mozleak 09:40:10 INFO - Running setup.py install for mozlog 09:40:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:40:10 INFO - Running setup.py install for moznetwork 09:40:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:40:11 INFO - Running setup.py install for mozprocess 09:40:11 INFO - Running setup.py install for mozprofile 09:40:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:40:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:40:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:40:11 INFO - Running setup.py install for mozrunner 09:40:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:40:11 INFO - Running setup.py install for mozscreenshot 09:40:11 INFO - Running setup.py install for moztest 09:40:11 INFO - Running setup.py install for mozversion 09:40:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:40:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:40:11 INFO - Cleaning up... 09:40:12 INFO - Return code: 0 09:40:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:12 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 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ada44bc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, '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.gkzy68R1dc/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.7slfFytNUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:40:12 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 09:40:12 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 09:40:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:40:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:12 INFO - 'HOME': '/Users/cltbld', 09:40:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:12 INFO - 'LOGNAME': 'cltbld', 09:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:12 INFO - 'MOZ_NO_REMOTE': '1', 09:40:12 INFO - 'NO_EM_RESTART': '1', 09:40:12 INFO - 'PAGER': '/bin/cat', 09:40:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:12 INFO - 'PWD': '/builds/slave/test', 09:40:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:12 INFO - 'SHELL': '/bin/bash', 09:40:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:40:12 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:40:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:12 INFO - 'USER': 'cltbld', 09:40:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:12 INFO - 'XPC_FLAGS': '0x0', 09:40:12 INFO - 'XPC_SERVICE_NAME': '0', 09:40:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-x3GqyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:12 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)) 09:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-53Ncl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:12 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)) 09:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-llep3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:12 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)) 09:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-tdgMcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:12 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)) 09:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-FIcoG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:12 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)) 09:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-V1PYop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:40:12 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)) 09:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:40:12 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Iambf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-G_PxJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-clbQ9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-TMWJum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-XqI2Zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-rBNQnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-5_G0fD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ndFMo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-k4nRVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-c0ffm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:40:13 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)) 09:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:40:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-1XYVYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:40:14 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)) 09:40:14 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)) 09:40:14 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)) 09:40:14 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)) 09:40:14 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)) 09:40:14 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)) 09:40:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40:16 INFO - Downloading blessings-1.6.tar.gz 09:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:40:16 INFO - Installing collected packages: blessings 09:40:16 INFO - Running setup.py install for blessings 09:40:16 INFO - Successfully installed blessings 09:40:16 INFO - Cleaning up... 09:40:16 INFO - Return code: 0 09:40:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:40:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:40:17 INFO - Reading from file tmpfile_stdout 09:40:17 INFO - Current package versions: 09:40:17 INFO - blessings == 1.6 09:40:17 INFO - blobuploader == 1.2.4 09:40:17 INFO - docopt == 0.6.1 09:40:17 INFO - functools32 == 3.2.3-2 09:40:17 INFO - jsonschema == 2.5.1 09:40:17 INFO - manifestparser == 1.1 09:40:17 INFO - mozInstall == 1.12 09:40:17 INFO - mozcrash == 0.17 09:40:17 INFO - mozdebug == 0.1 09:40:17 INFO - mozdevice == 0.48 09:40:17 INFO - mozfile == 1.2 09:40:17 INFO - mozhttpd == 0.7 09:40:17 INFO - mozinfo == 0.9 09:40:17 INFO - mozleak == 0.1 09:40:17 INFO - mozlog == 3.2 09:40:17 INFO - moznetwork == 0.27 09:40:17 INFO - mozprocess == 0.23 09:40:17 INFO - mozprofile == 0.28 09:40:17 INFO - mozrunner == 6.12 09:40:17 INFO - mozscreenshot == 0.1 09:40:17 INFO - mozsystemmonitor == 0.3 09:40:17 INFO - moztest == 0.7 09:40:17 INFO - mozversion == 1.4 09:40:17 INFO - psutil == 3.1.1 09:40:17 INFO - requests == 1.2.3 09:40:17 INFO - wsgiref == 0.1.2 09:40:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:17 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 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ada44bc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, '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.gkzy68R1dc/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.7slfFytNUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:40:17 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 09:40:17 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 09:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:17 INFO - 'HOME': '/Users/cltbld', 09:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:17 INFO - 'LOGNAME': 'cltbld', 09:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:17 INFO - 'MOZ_NO_REMOTE': '1', 09:40:17 INFO - 'NO_EM_RESTART': '1', 09:40:17 INFO - 'PAGER': '/bin/cat', 09:40:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:17 INFO - 'PWD': '/builds/slave/test', 09:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:17 INFO - 'SHELL': '/bin/bash', 09:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:40:17 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:17 INFO - 'USER': 'cltbld', 09:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:17 INFO - 'XPC_FLAGS': '0x0', 09:40:17 INFO - 'XPC_SERVICE_NAME': '0', 09:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:17 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)) 09:40:17 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)) 09:40:17 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)) 09:40:17 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)) 09:40:17 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)) 09:40:17 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)) 09:40:17 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)) 09:40:17 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)) 09:40:17 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)) 09:40:17 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)) 09:40:17 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)) 09:40:17 INFO - Cleaning up... 09:40:17 INFO - Return code: 0 09:40:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:17 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 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d16618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ada44bc60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, '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.gkzy68R1dc/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.7slfFytNUD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 09:40:17 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 09:40:17 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 09:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:17 INFO - 'HOME': '/Users/cltbld', 09:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:17 INFO - 'LOGNAME': 'cltbld', 09:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:17 INFO - 'MOZ_NO_REMOTE': '1', 09:40:17 INFO - 'NO_EM_RESTART': '1', 09:40:17 INFO - 'PAGER': '/bin/cat', 09:40:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:17 INFO - 'PWD': '/builds/slave/test', 09:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:17 INFO - 'SHELL': '/bin/bash', 09:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:40:17 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:17 INFO - 'USER': 'cltbld', 09:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:17 INFO - 'XPC_FLAGS': '0x0', 09:40:17 INFO - 'XPC_SERVICE_NAME': '0', 09:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:40:17 INFO - Cleaning up... 09:40:17 INFO - Return code: 0 09:40:17 INFO - Running post-action listener: _resource_record_post_action 09:40:17 INFO - Running post-action listener: _start_resource_monitoring 09:40:17 INFO - Starting resource monitoring. 09:40:17 INFO - ##### 09:40:17 INFO - ##### Finished create-virtualenv step (success) 09:40:17 INFO - ##### 09:40:17 INFO - ##### 09:40:17 INFO - ##### Running install step. 09:40:17 INFO - ##### 09:40:17 INFO - Running pre-action listener: _resource_record_pre_action 09:40:17 INFO - Running main action method: install 09:40:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:40:17 INFO - Reading from file tmpfile_stdout 09:40:17 INFO - Detecting whether we're running mozinstall >=1.0... 09:40:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:40:18 INFO - Reading from file tmpfile_stdout 09:40:18 INFO - Output received: 09:40:18 INFO - Usage: mozinstall [options] installer 09:40:18 INFO - Options: 09:40:18 INFO - -h, --help show this help message and exit 09:40:18 INFO - -d DEST, --destination=DEST 09:40:18 INFO - Directory to install application into. [default: 09:40:18 INFO - "/builds/slave/test"] 09:40:18 INFO - --app=APP Application being installed. [default: firefox] 09:40:18 INFO - mkdir: /builds/slave/test/build/application 09:40:18 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'] 09:40:18 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 09:40:53 INFO - Reading from file tmpfile_stdout 09:40:53 INFO - Output received: 09:40:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:40:53 INFO - Running post-action listener: _resource_record_post_action 09:40:53 INFO - ##### 09:40:53 INFO - ##### Finished install step (success) 09:40:53 INFO - ##### 09:40:53 INFO - ##### 09:40:53 INFO - ##### Running run-tests step. 09:40:53 INFO - ##### 09:40:53 INFO - Running pre-action listener: _resource_record_pre_action 09:40:53 INFO - Running main action method: run_tests 09:40:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:40:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:40:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:40:53 INFO - 2016-07-19 09:40:53.335 screenresolution[6307:105113] starting screenresolution argv=screenresolution get 09:40:53 INFO - 2016-07-19 09:40:53.338 screenresolution[6307:105113] Display 0: 1600x1200x32@60 09:40:53 INFO - 2016-07-19 09:40:53.343 screenresolution[6308:105116] starting screenresolution argv=screenresolution list 09:40:53 INFO - Available Modes on Display 0 09:40:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:40:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:40:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:40:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:40:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:40:53 INFO - Intel Iris: 09:40:53 INFO - Chipset Model: Intel Iris 09:40:53 INFO - Type: GPU 09:40:53 INFO - Bus: Built-In 09:40:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:40:53 INFO - Vendor: Intel (0x8086) 09:40:53 INFO - Device ID: 0x0a2e 09:40:53 INFO - Revision ID: 0x0009 09:40:53 INFO - Displays: 09:40:53 INFO - Display: 09:40:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:40:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:40:53 INFO - Display Serial Number: 1600x1200 60 09:40:53 INFO - Main Display: Yes 09:40:53 INFO - Mirror: Off 09:40:53 INFO - Online: Yes 09:40:53 INFO - Rotation: Supported 09:40:53 INFO - Return code: 0 09:40:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:40:53 INFO - Python 2.7.3 09:40:53 INFO - Return code: 0 09:40:53 INFO - grabbing minidump binary from tooltool 09:40: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')]} 09:40:53 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 0x100be5d30>, '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 0x10094adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100bddb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:40:53 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 09:40:53 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 09:40:53 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 09:40:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:40:53 INFO - Return code: 0 09:40:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:40:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:40:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:40:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:40:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:40:53 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/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 09:40:53 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/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --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 09:40:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gkzy68R1dc/Render', 09:40:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:53 INFO - 'HOME': '/Users/cltbld', 09:40:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:53 INFO - 'LOGNAME': 'cltbld', 09:40:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:40:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:40:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:53 INFO - 'MOZ_NO_REMOTE': '1', 09:40:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:40:53 INFO - 'NO_EM_RESTART': '1', 09:40:53 INFO - 'PAGER': '/bin/cat', 09:40:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:53 INFO - 'PWD': '/builds/slave/test', 09:40:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:40:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:53 INFO - 'SHELL': '/bin/bash', 09:40:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7slfFytNUD/Listeners', 09:40:53 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 09:40:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:53 INFO - 'USER': 'cltbld', 09:40:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:53 INFO - 'XPC_FLAGS': '0x0', 09:40:53 INFO - 'XPC_SERVICE_NAME': '0', 09:40:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:53 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/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 09:40:53 INFO - mozversion application_buildid: 20160719072820 09:40:53 INFO - mozversion application_changeset: c0ab769be276bf670f47d77ab470708beba15488 09:40:53 INFO - mozversion application_display_name: Nightly 09:40:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:40:53 INFO - mozversion application_name: Firefox 09:40:53 INFO - mozversion application_remotingname: firefox 09:40:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:40:53 INFO - mozversion application_vendor: Mozilla 09:40:53 INFO - mozversion application_version: 50.0a1 09:40:53 INFO - mozversion platform_buildid: 20160719072820 09:40:53 INFO - mozversion platform_changeset: c0ab769be276bf670f47d77ab470708beba15488 09:40:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:40:53 INFO - mozversion platform_version: 50.0a1 09:40:53 INFO - using testdate: 1468946453 09:40:53 INFO - actual date: 1468946453 09:40:53 INFO - starting webserver on 'localhost:56988' 09:40:53 INFO - SUITE-START | Running 2 tests 09:40:53 INFO - TEST-START | dromaeo_css 09:40:53 INFO - operating with platform_type : mac_ 09:40:53 INFO - Initialising browser for dromaeo_css test... 09:40:53 INFO - TEST-INFO | started process 6318 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpS7JO2K/profile http://localhost:56988/getInfo.html) 09:40:56 INFO - PROCESS | 6318 | __metrics Screen width/height:1600/1200 09:40:56 INFO - PROCESS | 6318 | colorDepth:24 09:40:56 INFO - PROCESS | 6318 | Browser inner width/height: 1024/689 09:40:56 INFO - PROCESS | 6318 | __metrics 09:40:56 INFO - PROCESS | 6318 | 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" 09:40:56 INFO - PROCESS | 6318 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:40:57 INFO - TEST-INFO | 6318: exit 0 09:40:57 INFO - Browser initialized. 09:40:57 INFO - Running cycle 1/1 for dromaeo_css test... 09:40:57 INFO - TEST-INFO | started process 6321 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpS7JO2K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:09 INFO - PROCESS | 6321 | RSS: Main: 245137408 09:41:09 INFO - PROCESS | 6321 | 09:42:00 INFO - PROCESS | 6321 | 1468946520923 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] 09:44:11 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:56988/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56988/tests/dromaeo/cssquery-ext.html) 09:44:13 INFO - PROCESS | 6321 | RSS: Main: 361967616 09:44:13 INFO - PROCESS | 6321 | 09:47:16 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:56988/tests/dromaeo/cssquery-ext.html (next: http://localhost:56988/tests/dromaeo/cssquery-jquery.html) 09:47:17 INFO - PROCESS | 6321 | RSS: Main: 382251008 09:47:17 INFO - PROCESS | 6321 | 09:50:19 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:56988/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56988/tests/dromaeo/cssquery-mootools.html) 09:50:19 INFO - PROCESS | 6321 | RSS: Main: 370012160 09:50:19 INFO - PROCESS | 6321 | 09:53:22 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:56988/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56988/tests/dromaeo/cssquery-prototype.html) 09:53:23 INFO - PROCESS | 6321 | RSS: Main: 371744768 09:53:23 INFO - PROCESS | 6321 | 09:56:26 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:56988/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56988/tests/dromaeo/cssquery-yui.html) 09:56:27 INFO - PROCESS | 6321 | RSS: Main: 337076224 09:56:27 INFO - PROCESS | 6321 | 09:59:30 INFO - PROCESS | 6321 | Cycle 1(1): loaded http://localhost:56988/tests/dromaeo/cssquery-yui.html (next: http://localhost:56988/tests/dromaeo/cssquery-dojo.html) 09:59:30 INFO - PROCESS | 6321 | RSS: Main: 432181248 09:59:30 INFO - PROCESS | 6321 | 09:59:30 INFO - PROCESS | 6321 | __start_tp_report 09:59:30 INFO - PROCESS | 6321 | _x_x_mozilla_page_load 09:59:30 INFO - PROCESS | 6321 | _x_x_mozilla_page_load_details 09:59:30 INFO - PROCESS | 6321 | |i|pagename|runs| 09:59:30 INFO - PROCESS | 6321 | |0;dojo.html;2859.756920661744;3319.502074688798;3332.0836769888283;3353.4131526982774;3392.762506624536;5622.987862184805;5733.025385684433;5742.827715168546;5808.564357673175;5836.503897168741;4690.765461726917;5728;5875.529957603392;5914.083317085859;5964.433378829015;4747.928781068269;5122.231665250224;5189.662671926331;5224.738763061831;5247.291615631898;5128.102582048145;5329.680219186861;5346.492083252085;5357.142857142858;5369.7583608737705;5169.147090730044;5207.297014902978;5220.608454365938;5238.476152384769;5251.527362537371;5201.427842937273;5206.817761378334;5209.218617207449;5221.634485586011;5243.554297884646;5206.427292997767;5221.321228240343;5244.1871509915645;5286.788528458857;5287.7884884604955;4670.789814458323;5648.181013753024;5715.31416230055;5762.481376676119;5821.563382746311;6184.412480814361;6228.377162283736;6234.688265586748;6255.530835187334;6261.87476250477;6152.84617884552;6181.690915454209;6198.194234749499;6198.81403557894;6234.968825155891;5781.710914454303;5840.737166827503;5908.940910590925;5915.142304365843;5936.376680448577;5381.7578208980685;6225.035119556476;6226.62640241587;6246.625202487865;6257.499400047986;6087.482563982023;6157.507399408037;6175.289841668266;6178.969105154535;6181.505479561624;6109.303871157975;6140.600860944024;6144.849302744094;6156.722947467375;6194.4115309045565;5504.614676972654;5835.153902684172;6150.231221097363;6152.323244443108;6220.564560480723;6106.603070800384;6123.25211838015;6203.906941395882;6210.254769427607;6211.16149319845;4890.804367825318;4931.112399768004;4956.90086198274;4968.701877887338;4987.0275296317595;4409.338599210144;4409.713368631029;4412.205802955435;4414.889627759332;4476.216662084123;5794.246747922827;5828.038373668297;5844.1818145459665;5852.4440178183;5908.438698323653;6145.647957449443;6149.84625384369;6177.62934223939;6189.50483961282;6229.595076320024;5704.486596206361;5758.827235182951;5801.477866991907;5830.096335068071;5842.152887831326;5419.566434685215;5419.566434685215;5429.158480435539;5436.972815135899;5447.591430642718;6131.877362452815;6270.780522681775;6299.559030867796;6301.14934483848;6315.810525684236;6124.167343420691;6142.775043102229;6157.983932651063;6166.506679465721;6174.536909731878;5290.206469029677;6258.749650013949;6260.311365749672;6262.780802671885;6265.310815810166;6075.3317135115985;6182.196314479089;6192.689172527871;6238.469730072995;6248.687565621792;5315.601329900351;5709.857253568694;6002.039673652194;6126.94520042658;6205.131281620486;6185.288691800501;6192.071189321638;6194.34959329282;6196.0705894116245;6205.875882482415;6057.939420605738;6109.2974307955155;6191.783287584913;6208.596441231305;6249.187605611241;5975.760969561343;6060.090986352083;6072.743717294343;6094.481969032682;6123.14276001369;6188.907166392598;6193.040078787795;6195.907061393992;6212.316645169119;6222.657753823583;5908.202392676933;5972.313183983897;5975.402459754164;6000.249968753906;6029.397060293935 09:59:30 INFO - PROCESS | 6321 | |1;ext.html;472.4967909176726;529.5551736541305;540.1870185371016;544.8528897197757;553.5958750112416;30110.946116885912;42049.78975105124;43225.351619725654;43243.567564324345;45703.54296457034;44589.77705111478;44646.77676611613;44960.77519612397;45050;45083.32375014369;8763.956180219122;9662.468564228982;9841.55712992917;9849.01509849013;9854.113129818315;8736.737897863075;8964.282857371427;8968.775780605505;8971.282297416225;9958.804943406802;11134.99785019348;11272.830907536394;11826.7634647307;12145.878541214563;12148.696282592962;11949.522019119226;12097.93951030248;12108.273503589724;12120.33338166405;12126.636200913987;8733.301335893113;11579.884201157965;11792.174547781658;11833.704157396134;11913.22564033335;30471.542926856117;42304.576954230375;42498.36252456216;42591.36112958308;42690;3469.3581687387914;3523.6828685418177;3674.81625918703;3834.827432765532;3842.846286148551;31605;31683.524747128813;32767.50848737271;33014.33971320584;33140.17149571256;3323.833808309575;3334.6331903490595;3341.365140623274;3380.5267262583257;3453.8963831085107;471100;559005;559658;560238.1988090115;561195;63906.36093639097;64864.0270395935;64885.67557162184;64903.675481623235;64931.67534162299;68437.65781171063;68621;68700;68724;68745.65627171833;16807.327706891832;17032.318707251572;17061.573460663334;17073.231704573325;17078.658426831393;13061.608151755463;13130;13133.60599182026;13159.53941612039;13160.868391316155;16595.31038921981;16834.410795622094;17036.65926681484;19459.318923837596;20366.79633203649;7255.8911616325795;7842.882356764653;8135.389845761473;8136.471129376571;8158.959205203936;918.8392031394479;941.924646028316;950.1638558068993;982.7936133411921;1027.1782573940818;28112.43775124486;30386.85007697256;31003.379932401225;31746.206344841565;32847.850325238505;1027.409239687183;1098.2367254758026;1099.8515200447998;1101.7410908436511;1147.6098126636984;16208.837911620969;16262.593435164215;16287.185640718153;16325.91837040831;16654.83345166557;729171;735101;735928;736292;736391;61298;61852;61862.69068654718;61910.380896190465;62179;1375.113052081422;1424.5441458733312;1447.558494659118;1449.9275036248357;1451.056813071512;25255;30754.231144221572;30812.383752325277;30868.691313087482;31579.684203158595;52910.47089529209;53116.46883531115;53543.6614084651;53546.99999999844;53608.73195634153;628449;632439;633302;633696.8315158394;634312;23633.936472859106;24606.138785142433;27014.864925675247;27139.72860271372;27467.450650987266;48718.53729615452;48942.021159575896;49421.76445588889;49521.50478495169;50007;40415.1916961665;40679.59320406876;40725.796371017954;41462.58537414708;41742.37386439266;39728.80135599303;40080.799596003;41151.5127483243;41174;41332.38001429921;38074.23851523009;38596.42105368478;38737.418938715375;38812.835614931595;38844;34340.969770906915;34770;35908.4613730789;35958.82020589985;35989.64010359863;24063.186650038682;24455.532668039974;24538.386540336633;25781.484370312864;26212.475750485264 09:59:30 INFO - PROCESS | 6321 | |2;jquery.html;351.4587535195799;351.68699857127154;374.4458201861244;381.83008561190263;403.45734986443244;15721.292541835617;16550.66898662026;20168.092435840386;20517.692234616494;20810.687839682378;21308.36074917755;21392.517666343498;21734.782652173475;22098.889505552448;22890.427739306484;5302.973485132569;5921.911171332434;6109.480694141003;6113.113598528211;6159.538034647397;4172.5410204877435;4370.99467122562;6011.098335249726;6357;6696;6556;6685.899711504307;6695.263521012685;6700.3969642732145;6717.126773519436;6473.255575608795;6627.7680281190405;6648.601083934979;6653.367930046637;6656.434203092743;5044.394672639307;5353.063213937584;5368.00691872005;5788.797392091299;5789.855253618651;13378.799318010238;13601.979851511176;13618.18290902549;23403.297901062997;26345.20964371053;2390.486045500226;2506.749325067497;2620.790336773073;2673.518766622007;2981.806182598124;10186.236032297607;10316.948415257875;10333.276670633059;10404.21968352369;10409.635662751767;1805.5034865412117;1827.6070644811296;1828.7988321284647;1846.3814622101613;1847.6489467001227;124256;126803;128378;136307;137118;9415.717528474157;11459.19785614999;11498.96509314149;11575.363355015386;11709.833680769085;14413.279336033365;14514.927425362806;14534.200618965848;14549.272536373139;14578.052426592238;7714.614269286513;8100.946876905965;8635.395522313378;8639.827203455896;8657.783555411164;8606.5696715164;8639.95680021596;8670.089640587774;8733.475991440533;8778.956105219433;4525.185466616041;4541.909161816745;4547.295169248771;4565.338025986213;4638.582527572535;1748.1696194307601;1800.1395986781963;1826.954326141857;1966.8426525877896;1982.762068551783;883.5361435246547;924.8531820542479;928.2944961829058;934.4299977014016;984.4536282363214;18107.45677629673;21153.471163220736;21351.572968540542;21388.465288367603;21408.57182856334;966.4636126949611;991.5339790298548;992.5781542844262;1009.9798004039878;1019.4800651667703;10671.466426678635;11297.661070167907;11526.88473115275;11564.653060408202;11566.248193867372;572149;605498.9725051346;618998.9050054721;632518;633474;79561.60219198867;122164;122437;125068;126195;988.8862780780303;989.4805227255605;997.1623835977814;1009.6718566465781;1009.8586197932295;16046.277917493975;17593.472195834147;19166;19357.90321048442;19440.61118777645;50519;52375;52714.73642631762;53344.73327633337;54187;75814.48371032438;76289.61855190911;76392.61803690947;77927;78346.29922204457;7205.207427183111;7558.546487210785;7597.278258565428;7638.50349727266;7859;12938.870611294144;13390.732185356044;13397.66505837362;13590.252536110213;13654.93172534131;9419.340646154977;11374.146938979444;11586;11604.245724027911;11759.882401175879;11308.151888608223;11433.59982400612;11500.597479088192;11590.71023224426;11795.764084718086;10945.56217751313;11662.70843228926;11878.762424751629;11895.524179032742;12097.032237421161;7572.886406703794;7588.430867685056;7664.5401275921395;7671;7735;10898.400587967737;10965.56137754481;11552.480138393961;11612.825807613062;11621.941890290494 09:59:30 INFO - PROCESS | 6321 | |3;mootools.html;1551.8060242469692;1597.8641815445685;1807.2228941555127;1938.8642795004337;1943.5529828139527;8534.658613655454;8832.028476867556;8968.968568614611;8994.86507702385;8997.370184087116;7050.259722729116;7613.8477230455355;7809;8787.253083487894;8802.64789408423;2364.6807680963066;2395.520895820834;2396.8015992004;2397.880105994693;2397.9520409591796;2361.6929799126087;2380.1193558383334;2397.2688330059327;2402.8438151520095;2403.1108489858775;2112.334614596405;2179.6512557990723;2288.290629904735;2367.538330025646;2374.845635033726;2350.4476448034698;2374.1334412939327;2402.0631953538136;2402.6636270922;2409.7469765674527;2390.6772585700883;2399.4841109161616;2425.260295609838;2425.757424257578;2431;8896.59965301563;8976.910230897673;8982.505962172077;8983.505907175102;8991.730248092568;5188.844334669966;5310.336207974004;5337.359516857963;5348.598855085884;5377.085895397791;3482.355764183634;3583.9104022399647;3639.636036396339;3644.1071937375514;3647.544056992876;1068.471106802138;1070.1706177712258;1080.2978064258216;1097.2812807611028;1110.7056629993058;11272.605458808903;11448.313101213955;11468.770624587461;11481.368524731137;11508.424578771112;4475.48531918827;4476.865694029184;4696.154692155376;4702.1065997461055;4730.645201609893;9298.74466946967;10017.949910250401;10025.749356266006;10045.397276163454;10050.346727462691;4812.951870481321;5224.216367544823;5293.867653308698;5296.549793267615;5313.707746074003;3904.6095390460728;4542.841000564964;4854.077725232195;4854.271859221074;4856.708597484162;3810.65704086628;3818.045488627843;3847.8076096195077;3891.727579069438;3923.1761330120426;1187.5427960235372;1202.0563857371922;1215.851020785367;1225.9509619615114;1233.7162452635764;812.4312980913385;908.4095338030334;911.6809116809196;979.2019504104132;1001.2240513602017;5885.911711324334;5938.317093534212;5946.494547963472;6042.758289668364;6148.538859585549;1039.781645854364;1047.450088703437;1053.4364115198332;1107.7064577886867;1111.5776005118003;4198.307279298942;4290.656747460196;4310.353446982977;4379.978100109479;4406.581374769392;10771.03060724523;10893;11048.392338421463;11084.61203857861;11121.388323642115;9810;10168.440735759605;10992.890071099346;11004.523982103963;11043.171762117874;7749.690012399441;8116.5941702914615;8172.978377702787;8246.422750407413;8261.710840120568;5767.8558036049435;5882.588218824641;5954.10688396744;6154.753809847735;6303.401176888188;9587.760305992406;9877;9903.009699030039;9918.05778451046;9938.10557049869;10152.39085654863;10238.027387398186;10244.385336879812;10268;10286.639967601099;7442.367398771165;7502.437317201341;7504.399648028036;7582.241775822374;7593.278638529331;8219.712310069119;8324.25081742646;8377.916220837713;8389.035260944825;8416.452930559493;6205.441510264098;6578.934210657832;7446.962765186139;7607.972923655457;7611.581363024865;7326.560406375635;7450.776476705708;7460.589667568119;7570.9242907570215;7597.6201189939175;6775.593464392152;7023.578585284899;7032.648367581498;7043.119610048744;7075.575465471882;4806.110869489155;5076.746162691777;5119.078565858182;5134.075866344094;5220.034293655686;5078.9238161426865;5137.537621613923;5165.845024649267;5178.275041494269;5331.760070796901 09:59:30 INFO - PROCESS | 6321 | |4;prototype.html;294.29222719359944;302.75930635145056;309.5588785979978;314.17215636797033;315.6653946816376;4895.922896962671;4939.8024079036795;5056.772445239963;5268.288781014567;5314.202869569566;4722.05558888222;4756.310335001423;4768.380110585619;5187.896242075156;5247.793007608252;3144.229663732389;3920.647141757241;4048.392741088838;4056.492938382702;4083.734557253769;3975.07209003633;3980.4716524791907;4039.6775450268224;4061.4110953911663;4098.971009350375;3515.9472607910902;3519.0469289544294;4013.3779264214095;4081.755094694313;4083.4079058536495;3808.638179372955;4010.659093977001;4036.5761595032686;4058.6753059755442;4063.7155399122075;3531.364354416204;3543.255916257589;3553.0813360785146;3556.199855032623;3577.373959557067;4150.958490415087;4325.632321252666;5005.424376196747;5118.820841270575;5221.39953905302;3809.9238015239816;4571.245744452194;4574.61115805157;4603.7698115094445;4627.038695235052;4595.471520775087;5506.64206826555;5530.668159910419;5542.812882592485;5549.001179787637;3157.857896394668;3387.3902697514436;3814.4659747635355;3816.5229346331707;3827.5981021992848;5760.395158508338;5787.33445653751;5788.305403351583;5816.418358164235;5829.562782791265;3519.788812671248;3869.496965394481;3879.991202287369;3880.301545721797;3891.8324502649093;3464.549608550872;3947.7236593438183;3947.921041579152;3957.4763715969557;3961.425593288957;4232.365145228179;4522.886927826765;4572.565606267399;4578.702384345007;4592.2422800238255;4255.446791917031;4819.831305904277;5481.616286859881;5581.469760372758;5642.561146907512;1007.5063219022626;1105.9612913547987;1295.8898493628146;1335.592644243515;1337.8461476930083;826.7974346285199;848.8811566380712;891.6491515354244;898.3711402018482;913.0823522359985;5137.306463627362;5141.76862041209;5156.20078887773;5166.948330516722;5178.964207158508;4530.592246697747;5161.458046905096;5614.382417934024;5628.607841667275;5630.549556035589;4856.83001094967;5077.365329333833;5079.492050794891;5111.463296353903;5120.487951204924;2154.9030293636824;2231.866088034723;2330.4618951491934;2474.8762561872195;2531.962020569693;3902.9609703903166;4187.790610469525;4408.6693497987135;4912.533309335608;4983.750812459362;4414.227510185769;4761.095561775318;4784.952150478521;4832.251001094836;4861.368022157097;4381.759003254788;4422.7346359218545;4868.488808675038;4878.658493905393;4885.313077807192;3874.38009918423;4364.6944713871;4608.30875368684;4636.884077898079;4770.6183505318795;4486.304622783343;4506.35518035908;4643.512431194676;4670.439547254351;4750.453697824656;4994.400671919393;5394.487523685244;5608.691521966331;5953.791617293374;6073.969630151821;3825.6365645263654;3867.781648780625;3894.805259736945;3915.314819906448;3916.5300163980505;5175.327207463006;5411.161270003156;5883.970580147072;5917.526597872074;5918.23063001807;4739.905201896012;5105.957616953248;5421.9186712200135;5517.972410137923;5641.887162256814;5585.497305242548;5590.692511911884;5605;5612;5651.350094739158;5177.456367081402;5494.230807687007;5508.421615730451;5514.36584792754;5573.191887176417;4611.631069514364;5265.710385928657;5369.436209197978;5618.297712785902;5625.943740562706;5485.491489840326;5487.067198576172;5499.532539734168;5509.724513774215;5515.7732726353925 09:59:30 INFO - PROCESS | 6321 | |5;yui.html;725.2711025419454;838.1827717974971;851.5188918261182;852.8294341131775;868.3443999780167;13806.516771912973;14818.407263709452;14828.961972661918;14850.1832399218;15832.762508562351;13212.735745285088;16375.918120409411;16396.426125085625;16400.75398869018;16499.34002639896;695.7843068648709;759.2028370211284;879.3141349747192;911.9452832830051;914.8399030169694;713.1050531582856;762.6034462079715;870.1516021878681;902.7968707040897;904.2268860124598;932.1703683721494;932.2728271947891;936.5925822267302;939.5818860607033;941.1764705882352;732.5531425830239;914.5335878701843;935.3452583191793;936.8688383626263;936.9531523423801;696.4324075878194;696.4846013949691;697.2817997462622;698.0053423870963;699.4509310191476;15017.023893447;15042;15068.020578662461;15086.54740357791;15113.3199006045;14000.92999535006;14149.632764845015;14150.575482735534;14233.217173055478;14348.210848403334;13578.185308881499;13606.115602485806;13759.656008599764;13821.170729756146;14189.858101418957;4673.369095172142;4706.7646617669325;4728.976355118237;4782.545658162469;4849.151398505247;127637;128507;130282;130581.34709326488;131191.34404327918;10997.395143267036;11012.669619911416;11119.388433636066;11162;11655.475503602358;21505.924703764747;21642.35072947814;21659.133634654754;21669.458263543536;21688.34934951954;7931.444798864024;7960.601969901481;8012.238837310446;8044.074931382849;8199.71301004462;4393.1872603568445;4456.821727130879;4477.462704475483;4527.524609915978;4634.443866736014;3450.3789317922515;3543.982280088583;3569.4824250483534;3574.660407261306;3584.444411116281;639.0286764118514;653.2194028136331;657.1653999420708;663.2670898657011;671.2951401028997;606.9635821850703;622.8474023864183;640.3638703113589;647.4270270810367;653.323809856796;9109.274380602179;9192;9322.813543729224;9475.336726429221;9749.902500975042;618.1994317359031;620.385818040137;621.5493767018966;639.3957709964133;640.4556127291858;3837.865674701372;4447.488538818013;4488.842890498817;4500.887477813081;4520.141173177086;126452;156012;169974.60050798915;170272.44591331139;170517;36702.81648591793;83232;144159;145125.5487445133;148906;124603.37698311632;124957;127205.36397317954;127404;130059.34970325087;8631.611577478903;8672.132786721277;8683.435576687496;8712.390132690904;8866.601002954754;67603;70165.29834701588;70423.59152817017;70740.58518829492;71776;97244;98826.01173988456;105838.47080764547;105863.47068264609;107329;11628.18602697803;11631.59289424866;11909.583164589198;12134.514619415124;12217.93891030539;12921.612351629467;13024.739505210033;13064.085514013928;13425.0602457827;13425.395857186255;10353.382820572871;12020.63938081859;12031.15781895259;12152.513899443924;12563.371831408576;11793.351365674627;12083.82744222674;12205.328706920849;12537.435815388511;12542.561010364594;11795.882041179479;11859.169858110195;11863.406829658656;12200.084993625691;12239;7940.483868548526;10092.798144037224;10138.341007834468;10473.31923424975;10528.263021588415;8798.296136308953;9387.014363491735;9618.580418498497;10093.646722364681;10099.79800403973 09:59:30 INFO - PROCESS | 6321 | __end_tp_report 09:59:30 INFO - PROCESS | 6321 | __start_cc_report 09:59:30 INFO - PROCESS | 6321 | _x_x_mozilla_cycle_collect,1226 09:59:30 INFO - PROCESS | 6321 | __end_cc_report 09:59:30 INFO - PROCESS | 6321 | __startTimestamp1468947570310__endTimestamp 09:59:30 INFO - PROCESS | 6321 | 09:59:30 INFO - PROCESS | 6321 | ------- Summary: start ------- 09:59:30 INFO - PROCESS | 6321 | Number of tests: 6 09:59:30 INFO - PROCESS | 6321 | 09:59:30 INFO - PROCESS | 6321 | [#0] dojo.html Cycles:165 Average:5722.24 Median:5972.31 stddev:652.13 (10.9%) stddev-sans-first:614.25 09:59:30 INFO - PROCESS | 6321 | Values: 2859.8 3319.5 3332.1 3353.4 3392.8 5623.0 5733.0 5742.8 5808.6 5836.5 4690.8 5728.0 5875.5 5914.1 5964.4 4747.9 5122.2 5189.7 5224.7 5247.3 5128.1 5329.7 5346.5 5357.1 5369.8 5169.1 5207.3 5220.6 5238.5 5251.5 5201.4 5206.8 5209.2 5221.6 5243.6 5206.4 5221.3 5244.2 5286.8 5287.8 4670.8 5648.2 5715.3 5762.5 5821.6 6184.4 6228.4 6234.7 6255.5 6261.9 6152.8 6181.7 6198.2 6198.8 6235.0 5781.7 5840.7 5908.9 5915.1 5936.4 5381.8 6225.0 6226.6 6246.6 6257.5 6087.5 6157.5 6175.3 6179.0 6181.5 6109.3 6140.6 6144.8 6156.7 6194.4 5504.6 5835.2 6150.2 6152.3 6220.6 6106.6 6123.3 6203.9 6210.3 6211.2 4890.8 4931.1 4956.9 4968.7 4987.0 4409.3 4409.7 4412.2 4414.9 4476.2 5794.2 5828.0 5844.2 5852.4 5908.4 6145.6 6149.8 6177.6 6189.5 6229.6 5704.5 5758.8 5801.5 5830.1 5842.2 5419.6 5419.6 5429.2 5437.0 5447.6 6131.9 6270.8 6299.6 6301.1 6315.8 6124.2 6142.8 6158.0 6166.5 6174.5 5290.2 6258.7 6260.3 6262.8 6265.3 6075.3 6182.2 6192.7 6238.5 6248.7 5315.6 5709.9 6002.0 6126.9 6205.1 6185.3 6192.1 6194.3 6196.1 6205.9 6057.9 6109.3 6191.8 6208.6 6249.2 5975.8 6060.1 6072.7 6094.5 6123.1 6188.9 6193.0 6195.9 6212.3 6222.7 5908.2 5972.3 5975.4 6000.2 6029.4 09:59:30 INFO - PROCESS | 6321 | 09:59:30 INFO - PROCESS | 6321 | [#1] ext.html Cycles:180 Average:76941.96 Median:38828.42 stddev:171839.19 (442.6%) stddev-sans-first:172225.32 09:59:30 INFO - PROCESS | 6321 | Values: 472.5 529.6 540.2 544.9 553.6 30110.9 42049.8 43225.4 43243.6 45703.5 44589.8 44646.8 44960.8 45050.0 45083.3 8764.0 9662.5 9841.6 9849.0 9854.1 8736.7 8964.3 8968.8 8971.3 9958.8 11135.0 11272.8 11826.8 12145.9 12148.7 11949.5 12097.9 12108.3 12120.3 12126.6 8733.3 11579.9 11792.2 11833.7 11913.2 30471.5 42304.6 42498.4 42591.4 42690.0 3469.4 3523.7 3674.8 3834.8 3842.8 31605.0 31683.5 32767.5 33014.3 33140.2 3323.8 3334.6 3341.4 3380.5 3453.9 471100.0 559005.0 559658.0 560238.2 561195.0 63906.4 64864.0 64885.7 64903.7 64931.7 68437.7 68621.0 68700.0 68724.0 68745.7 16807.3 17032.3 17061.6 17073.2 17078.7 13061.6 13130.0 13133.6 13159.5 13160.9 16595.3 16834.4 17036.7 19459.3 20366.8 7255.9 7842.9 8135.4 8136.5 8159.0 918.8 941.9 950.2 982.8 1027.2 28112.4 30386.9 31003.4 31746.2 32847.9 1027.4 1098.2 1099.9 1101.7 1147.6 16208.8 16262.6 16287.2 16325.9 16654.8 729171.0 735101.0 735928.0 736292.0 736391.0 61298.0 61852.0 61862.7 61910.4 62179.0 1375.1 1424.5 1447.6 1449.9 1451.1 25255.0 30754.2 30812.4 30868.7 31579.7 52910.5 53116.5 53543.7 53547.0 53608.7 628449.0 632439.0 633302.0 633696.8 634312.0 23633.9 24606.1 27014.9 27139.7 27467.5 48718.5 48942.0 49421.8 49521.5 50007.0 40415.2 40679.6 40725.8 41462.6 41742.4 39728.8 40080.8 41151.5 41174.0 41332.4 38074.2 38596.4 38737.4 38812.8 38844.0 34341.0 34770.0 35908.5 35958.8 35989.6 24063.2 24455.5 24538.4 25781.5 26212.5 09:59:30 INFO - PROCESS | 6321 | 09:59:30 INFO - PROCESS | 6321 | [#2] jquery.html Cycles:180 Average:35236.67 Median:2448.62 stddev:102217.64 (4174.5%) stddev-sans-first:102470.82 09:59:30 INFO - PROCESS | 6321 | Values: 351.5 351.7 374.4 381.8 403.5 15721.3 16550.7 20168.1 20517.7 20810.7 21308.4 21392.5 21734.8 22098.9 22890.4 5303.0 5921.9 6109.5 6113.1 6159.5 4172.5 4371.0 6011.1 6357.0 6696.0 6556.0 6685.9 6695.3 6700.4 6717.1 6473.3 6627.8 6648.6 6653.4 6656.4 5044.4 5353.1 5368.0 5788.8 5789.9 13378.8 13602.0 13618.2 23403.3 26345.2 2390.5 2506.7 2620.8 2673.5 2981.8 10186.2 10316.9 10333.3 10404.2 10409.6 1805.5 1827.6 1828.8 1846.4 1847.6 124256.0 126803.0 128378.0 136307.0 137118.0 9415.7 11459.2 11499.0 11575.4 11709.8 14413.3 14514.9 14534.2 14549.3 14578.1 7714.6 8100.9 8635.4 8639.8 8657.8 8606.6 8640.0 8670.1 8733.5 8779.0 4525.2 4541.9 4547.3 4565.3 4638.6 1748.2 1800.1 1827.0 1966.8 1982.8 883.5 924.9 928.3 934.4 984.5 18107.5 21153.5 21351.6 21388.5 21408.6 966.5 991.5 992.6 1010.0 1019.5 10671.5 11297.7 11526.9 11564.7 11566.2 572149.0 605499.0 618998.9 632518.0 633474.0 79561.6 122164.0 122437.0 125068.0 126195.0 988.9 989.5 997.2 1009.7 1009.9 16046.3 17593.5 19166.0 19357.9 19440.6 50519.0 52375.0 52714.7 53344.7 54187.0 75814.5 76289.6 76392.6 77927.0 78346.3 7205.2 7558.5 7597.3 7638.5 7859.0 12938.9 13390.7 13397.7 13590.3 13654.9 9419.3 11374.1 11586.0 11604.2 11759.9 11308.2 11433.6 11500.6 11590.7 11795.8 10945.6 11662.7 11878.8 11895.5 12097.0 7572.9 7588.4 7664.5 7671.0 7735.0 10898.4 10965.6 11552.5 11612.8 11621.9 09:59:30 INFO - PROCESS | 6321 | 09:59:30 INFO - PROCESS | 6321 | [#3] mootools.html Cycles:180 Average:5723.22 Median:4768.38 stddev:3174.14 (66.6%) stddev-sans-first:3167.57 09:59:30 INFO - PROCESS | 6321 | Values: 1551.8 1597.9 1807.2 1938.9 1943.6 8534.7 8832.0 8969.0 8994.9 8997.4 7050.3 7613.8 7809.0 8787.3 8802.6 2364.7 2395.5 2396.8 2397.9 2398.0 2361.7 2380.1 2397.3 2402.8 2403.1 2112.3 2179.7 2288.3 2367.5 2374.8 2350.4 2374.1 2402.1 2402.7 2409.7 2390.7 2399.5 2425.3 2425.8 2431.0 8896.6 8976.9 8982.5 8983.5 8991.7 5188.8 5310.3 5337.4 5348.6 5377.1 3482.4 3583.9 3639.6 3644.1 3647.5 1068.5 1070.2 1080.3 1097.3 1110.7 11272.6 11448.3 11468.8 11481.4 11508.4 4475.5 4476.9 4696.2 4702.1 4730.6 9298.7 10017.9 10025.7 10045.4 10050.3 4813.0 5224.2 5293.9 5296.5 5313.7 3904.6 4542.8 4854.1 4854.3 4856.7 3810.7 3818.0 3847.8 3891.7 3923.2 1187.5 1202.1 1215.9 1226.0 1233.7 812.4 908.4 911.7 979.2 1001.2 5885.9 5938.3 5946.5 6042.8 6148.5 1039.8 1047.5 1053.4 1107.7 1111.6 4198.3 4290.7 4310.4 4380.0 4406.6 10771.0 10893.0 11048.4 11084.6 11121.4 9810.0 10168.4 10992.9 11004.5 11043.2 7749.7 8116.6 8173.0 8246.4 8261.7 5767.9 5882.6 5954.1 6154.8 6303.4 9587.8 9877.0 9903.0 9918.1 9938.1 10152.4 10238.0 10244.4 10268.0 10286.6 7442.4 7502.4 7504.4 7582.2 7593.3 8219.7 8324.3 8377.9 8389.0 8416.5 6205.4 6578.9 7447.0 7608.0 7611.6 7326.6 7450.8 7460.6 7570.9 7597.6 6775.6 7023.6 7032.6 7043.1 7075.6 4806.1 5076.7 5119.1 5134.1 5220.0 5078.9 5137.5 5165.8 5178.3 5331.8 09:59:30 INFO - PROCESS | 6321 | 09:59:30 INFO - PROCESS | 6321 | [#4] prototype.html Cycles:180 Average:4335.73 Median:4730.98 stddev:1333.48 (28.2%) stddev-sans-first:1302.27 09:59:30 INFO - PROCESS | 6321 | Values: 294.3 302.8 309.6 314.2 315.7 4895.9 4939.8 5056.8 5268.3 5314.2 4722.1 4756.3 4768.4 5187.9 5247.8 3144.2 3920.6 4048.4 4056.5 4083.7 3975.1 3980.5 4039.7 4061.4 4099.0 3515.9 3519.0 4013.4 4081.8 4083.4 3808.6 4010.7 4036.6 4058.7 4063.7 3531.4 3543.3 3553.1 3556.2 3577.4 4151.0 4325.6 5005.4 5118.8 5221.4 3809.9 4571.2 4574.6 4603.8 4627.0 4595.5 5506.6 5530.7 5542.8 5549.0 3157.9 3387.4 3814.5 3816.5 3827.6 5760.4 5787.3 5788.3 5816.4 5829.6 3519.8 3869.5 3880.0 3880.3 3891.8 3464.5 3947.7 3947.9 3957.5 3961.4 4232.4 4522.9 4572.6 4578.7 4592.2 4255.4 4819.8 5481.6 5581.5 5642.6 1007.5 1106.0 1295.9 1335.6 1337.8 826.8 848.9 891.6 898.4 913.1 5137.3 5141.8 5156.2 5166.9 5179.0 4530.6 5161.5 5614.4 5628.6 5630.5 4856.8 5077.4 5079.5 5111.5 5120.5 2154.9 2231.9 2330.5 2474.9 2532.0 3903.0 4187.8 4408.7 4912.5 4983.8 4414.2 4761.1 4785.0 4832.3 4861.4 4381.8 4422.7 4868.5 4878.7 4885.3 3874.4 4364.7 4608.3 4636.9 4770.6 4486.3 4506.4 4643.5 4670.4 4750.5 4994.4 5394.5 5608.7 5953.8 6074.0 3825.6 3867.8 3894.8 3915.3 3916.5 5175.3 5411.2 5884.0 5917.5 5918.2 4739.9 5106.0 5421.9 5518.0 5641.9 5585.5 5590.7 5605.0 5612.0 5651.4 5177.5 5494.2 5508.4 5514.4 5573.2 4611.6 5265.7 5369.4 5618.3 5625.9 5485.5 5487.1 5499.5 5509.7 5515.8 09:59:30 INFO - PROCESS | 6321 | 09:59:30 INFO - PROCESS | 6321 | [#5] yui.html Cycles:180 Average:26031.48 Median:3579.55 stddev:43230.24 (1207.7%) stddev-sans-first:43309.76 09:59:30 INFO - PROCESS | 6321 | Values: 725.3 838.2 851.5 852.8 868.3 13806.5 14818.4 14829.0 14850.2 15832.8 13212.7 16375.9 16396.4 16400.8 16499.3 695.8 759.2 879.3 911.9 914.8 713.1 762.6 870.2 902.8 904.2 932.2 932.3 936.6 939.6 941.2 732.6 914.5 935.3 936.9 937.0 696.4 696.5 697.3 698.0 699.5 15017.0 15042.0 15068.0 15086.5 15113.3 14000.9 14149.6 14150.6 14233.2 14348.2 13578.2 13606.1 13759.7 13821.2 14189.9 4673.4 4706.8 4729.0 4782.5 4849.2 127637.0 128507.0 130282.0 130581.3 131191.3 10997.4 11012.7 11119.4 11162.0 11655.5 21505.9 21642.4 21659.1 21669.5 21688.3 7931.4 7960.6 8012.2 8044.1 8199.7 4393.2 4456.8 4477.5 4527.5 4634.4 3450.4 3544.0 3569.5 3574.7 3584.4 639.0 653.2 657.2 663.3 671.3 607.0 622.8 640.4 647.4 653.3 9109.3 9192.0 9322.8 9475.3 9749.9 618.2 620.4 621.5 639.4 640.5 3837.9 4447.5 4488.8 4500.9 4520.1 126452.0 156012.0 169974.6 170272.4 170517.0 36702.8 83232.0 144159.0 145125.5 148906.0 124603.4 124957.0 127205.4 127404.0 130059.3 8631.6 8672.1 8683.4 8712.4 8866.6 67603.0 70165.3 70423.6 70740.6 71776.0 97244.0 98826.0 105838.5 105863.5 107329.0 11628.2 11631.6 11909.6 12134.5 12217.9 12921.6 13024.7 13064.1 13425.1 13425.4 10353.4 12020.6 12031.2 12152.5 12563.4 11793.4 12083.8 12205.3 12537.4 12542.6 11795.9 11859.2 11863.4 12200.1 12239.0 7940.5 10092.8 10138.3 10473.3 10528.3 8798.3 9387.0 9618.6 10093.6 10099.8 09:59:30 INFO - PROCESS | 6321 | -------- Summary: end -------- 09:59:30 INFO - PROCESS | 6321 | 09:59:30 INFO - TEST-INFO | 6321: exit 0 09:59:30 INFO - TEST-OK | dromaeo_css | took 1117001ms 09:59:30 INFO - TEST-START | kraken 09:59:30 INFO - operating with platform_type : mac_ 09:59:30 INFO - Initialising browser for kraken test... 09:59:30 INFO - TEST-INFO | started process 6324 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpxTrcsx/profile http://localhost:56988/getInfo.html) 09:59:33 INFO - PROCESS | 6324 | __metrics Screen width/height:1600/1200 09:59:33 INFO - PROCESS | 6324 | colorDepth:24 09:59:33 INFO - PROCESS | 6324 | Browser inner width/height: 1024/689 09:59:33 INFO - PROCESS | 6324 | __metrics 09:59:33 INFO - PROCESS | 6324 | 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" 09:59:33 INFO - PROCESS | 6324 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:59:34 INFO - TEST-INFO | 6324: exit 0 09:59:34 INFO - Browser initialized. 09:59:34 INFO - Running cycle 1/1 for kraken test... 09:59:34 INFO - TEST-INFO | started process 6325 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpxTrcsx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:46 INFO - PROCESS | 6325 | RSS: Main: 245231616 09:59:46 INFO - PROCESS | 6325 | 09:59:48 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56988/tests/kraken/driver.html?testName=audio-beat-detection) 09:59:49 INFO - PROCESS | 6325 | RSS: Main: 294625280 09:59:49 INFO - PROCESS | 6325 | 09:59:52 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56988/tests/kraken/driver.html?testName=audio-dft) 09:59:53 INFO - PROCESS | 6325 | RSS: Main: 304750592 09:59:53 INFO - PROCESS | 6325 | 09:59:56 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56988/tests/kraken/driver.html?testName=audio-fft) 09:59:57 INFO - PROCESS | 6325 | RSS: Main: 391208960 09:59:57 INFO - PROCESS | 6325 | 09:59:59 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56988/tests/kraken/driver.html?testName=audio-oscillator) 09:59:59 INFO - PROCESS | 6325 | RSS: Main: 293130240 09:59:59 INFO - PROCESS | 6325 | 10:00:02 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56988/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:00:02 INFO - PROCESS | 6325 | RSS: Main: 297279488 10:00:02 INFO - PROCESS | 6325 | 10:00:05 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56988/tests/kraken/driver.html?testName=imaging-darkroom) 10:00:06 INFO - PROCESS | 6325 | RSS: Main: 334872576 10:00:06 INFO - PROCESS | 6325 | 10:00:09 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56988/tests/kraken/driver.html?testName=imaging-desaturate) 10:00:10 INFO - PROCESS | 6325 | RSS: Main: 335380480 10:00:10 INFO - PROCESS | 6325 | 10:00:12 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56988/tests/kraken/driver.html?testName=json-parse-financial) 10:00:13 INFO - PROCESS | 6325 | RSS: Main: 334622720 10:00:13 INFO - PROCESS | 6325 | 10:00:15 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56988/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:00:15 INFO - PROCESS | 6325 | RSS: Main: 314036224 10:00:15 INFO - PROCESS | 6325 | 10:00:17 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56988/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:00:18 INFO - PROCESS | 6325 | RSS: Main: 320991232 10:00:18 INFO - PROCESS | 6325 | 10:00:20 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56988/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:00:21 INFO - PROCESS | 6325 | RSS: Main: 318476288 10:00:21 INFO - PROCESS | 6325 | 10:00:24 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56988/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:00:24 INFO - PROCESS | 6325 | RSS: Main: 328478720 10:00:24 INFO - PROCESS | 6325 | 10:00:27 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56988/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:00:28 INFO - PROCESS | 6325 | RSS: Main: 330940416 10:00:28 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | Cycle 1(1): loaded http://localhost:56988/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56988/tests/kraken/driver.html?testName=ai-astar) 10:00:30 INFO - PROCESS | 6325 | RSS: Main: 374763520 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | __start_tp_report 10:00:30 INFO - PROCESS | 6325 | _x_x_mozilla_page_load 10:00:30 INFO - PROCESS | 6325 | _x_x_mozilla_page_load_details 10:00:30 INFO - PROCESS | 6325 | |i|pagename|runs| 10:00:30 INFO - PROCESS | 6325 | |0;ai-astar;102;113;114;113;113;114;113;112;111;113 10:00:30 INFO - PROCESS | 6325 | |1;audio-beat-detection;143;107;106;161;106;107;150;136;151;107 10:00:30 INFO - PROCESS | 6325 | |2;audio-dft;187;195;259;198;185;182;185;184;185;201 10:00:30 INFO - PROCESS | 6325 | |3;audio-fft;79;74;58;89;57;57;65;63;82;56 10:00:30 INFO - PROCESS | 6325 | |4;audio-oscillator;84;93;93;57;66;79;130;100;98;137 10:00:30 INFO - PROCESS | 6325 | |5;imaging-gaussian-blur;89;89;94;89;92;110;93;89;92;88 10:00:30 INFO - PROCESS | 6325 | |6;imaging-darkroom;95;97;96;107;148;149;95;136;149;96 10:00:30 INFO - PROCESS | 6325 | |7;imaging-desaturate;125;133;87;86;87;86;86;83;84;84 10:00:30 INFO - PROCESS | 6325 | |8;json-parse-financial;53;52;53;53;52;54;51;51;52;52 10:00:30 INFO - PROCESS | 6325 | |9;json-stringify-tinderbox;67;76;77;78;74;72;71;76;73;75 10:00:30 INFO - PROCESS | 6325 | |10;stanford-crypto-aes;84;85;83;92;101;82;86;83;83;91 10:00:30 INFO - PROCESS | 6325 | |11;stanford-crypto-ccm;138;137;146;150;134;139;126;158;207;145 10:00:30 INFO - PROCESS | 6325 | |12;stanford-crypto-pbkdf2;164;163;163;164;178;225;170;165;165;161 10:00:30 INFO - PROCESS | 6325 | |13;stanford-crypto-sha256-iterative;66;67;66;66;67;65;66;66;65;66 10:00:30 INFO - PROCESS | 6325 | __end_tp_report 10:00:30 INFO - PROCESS | 6325 | __start_cc_report 10:00:30 INFO - PROCESS | 6325 | _x_x_mozilla_cycle_collect,2147 10:00:30 INFO - PROCESS | 6325 | __end_cc_report 10:00:30 INFO - PROCESS | 6325 | __startTimestamp1468947630396__endTimestamp 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | ------- Summary: start ------- 10:00:30 INFO - PROCESS | 6325 | Number of tests: 14 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.55 (3.1%) stddev-sans-first:0.93 10:00:30 INFO - PROCESS | 6325 | Values: 102.0 113.0 114.0 113.0 113.0 114.0 113.0 112.0 111.0 113.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:139.50 stddev:22.80 (16.3%) stddev-sans-first:23.47 10:00:30 INFO - PROCESS | 6325 | Values: 143.0 107.0 106.0 161.0 106.0 107.0 150.0 136.0 151.0 107.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#2] audio-dft Cycles:10 Average:196.10 Median:191.00 stddev:23.05 (12.1%) stddev-sans-first:24.21 10:00:30 INFO - PROCESS | 6325 | Values: 187.0 195.0 259.0 198.0 185.0 182.0 185.0 184.0 185.0 201.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#3] audio-fft Cycles:10 Average:68.00 Median:69.50 stddev:12.08 (17.4%) stddev-sans-first:12.14 10:00:30 INFO - PROCESS | 6325 | Values: 79.0 74.0 58.0 89.0 57.0 57.0 65.0 63.0 82.0 56.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:81.50 stddev:25.11 (30.8%) stddev-sans-first:26.39 10:00:30 INFO - PROCESS | 6325 | Values: 84.0 93.0 93.0 57.0 66.0 79.0 130.0 100.0 98.0 137.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#5] imaging-gaussian-blur Cycles:10 Average:92.50 Median:90.50 stddev:6.49 (7.2%) stddev-sans-first:6.75 10:00:30 INFO - PROCESS | 6325 | Values: 89.0 89.0 94.0 89.0 92.0 110.0 93.0 89.0 92.0 88.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#6] imaging-darkroom Cycles:10 Average:116.80 Median:95.00 stddev:25.21 (26.5%) stddev-sans-first:25.47 10:00:30 INFO - PROCESS | 6325 | Values: 95.0 97.0 96.0 107.0 148.0 149.0 95.0 136.0 149.0 96.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:86.00 stddev:18.54 (21.6%) stddev-sans-first:15.94 10:00:30 INFO - PROCESS | 6325 | Values: 125.0 133.0 87.0 86.0 87.0 86.0 86.0 83.0 84.0 84.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.50 stddev:0.95 (1.8%) stddev-sans-first:0.97 10:00:30 INFO - PROCESS | 6325 | Values: 53.0 52.0 53.0 53.0 52.0 54.0 51.0 51.0 52.0 52.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#9] json-stringify-tinderbox Cycles:10 Average:73.90 Median:75.50 stddev:3.28 (4.3%) stddev-sans-first:2.35 10:00:30 INFO - PROCESS | 6325 | Values: 67.0 76.0 77.0 78.0 74.0 72.0 71.0 76.0 73.0 75.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:84.50 stddev:6.00 (7.1%) stddev-sans-first:6.26 10:00:30 INFO - PROCESS | 6325 | Values: 84.0 85.0 83.0 92.0 101.0 82.0 86.0 83.0 83.0 91.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:145.50 stddev:22.56 (15.5%) stddev-sans-first:23.63 10:00:30 INFO - PROCESS | 6325 | Values: 138.0 137.0 146.0 150.0 134.0 139.0 126.0 158.0 207.0 145.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:165.00 stddev:19.31 (11.7%) stddev-sans-first:20.28 10:00:30 INFO - PROCESS | 6325 | Values: 164.0 163.0 163.0 164.0 178.0 225.0 170.0 165.0 165.0 161.0 10:00:30 INFO - PROCESS | 6325 | 10:00:30 INFO - PROCESS | 6325 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 10:00:30 INFO - PROCESS | 6325 | Values: 66.0 67.0 66.0 66.0 67.0 65.0 66.0 66.0 65.0 66.0 10:00:30 INFO - PROCESS | 6325 | -------- Summary: end -------- 10:00:30 INFO - PROCESS | 6325 | 10:00:31 INFO - TEST-INFO | 6325: exit 0 10:00:31 INFO - TEST-OK | kraken | took 60476ms 10:00:31 INFO - SUITE-END | took 1177s 10:00:31 INFO - Completed test suite (00:19:37) 10:00:31 INFO - javascript benchmark 10:00:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2859.756920661744, 3319.502074688798, 3332.0836769888283, 3353.4131526982774, 3392.762506624536, 5622.987862184805, 5733.025385684433, 5742.827715168546, 5808.564357673175, 5836.503897168741, 4690.765461726917, 5728.0, 5875.529957603392, 5914.083317085859, 5964.433378829015, 4747.928781068269, 5122.231665250224, 5189.662671926331, 5224.738763061831, 5247.291615631898, 5128.102582048145, 5329.680219186861, 5346.492083252085, 5357.142857142858, 5369.7583608737705, 5169.147090730044, 5207.297014902978, 5220.608454365938, 5238.476152384769, 5251.527362537371, 5201.427842937273, 5206.817761378334, 5209.218617207449, 5221.634485586011, 5243.554297884646, 5206.427292997767, 5221.321228240343, 5244.1871509915645, 5286.788528458857, 5287.7884884604955, 4670.789814458323, 5648.181013753024, 5715.31416230055, 5762.481376676119, 5821.563382746311, 6184.412480814361, 6228.377162283736, 6234.688265586748, 6255.530835187334, 6261.87476250477, 6152.84617884552, 6181.690915454209, 6198.194234749499, 6198.81403557894, 6234.968825155891, 5781.710914454303, 5840.737166827503, 5908.940910590925, 5915.142304365843, 5936.376680448577, 5381.7578208980685, 6225.035119556476, 6226.62640241587, 6246.625202487865, 6257.499400047986, 6087.482563982023, 6157.507399408037, 6175.289841668266, 6178.969105154535, 6181.505479561624, 6109.303871157975, 6140.600860944024, 6144.849302744094, 6156.722947467375, 6194.4115309045565, 5504.614676972654, 5835.153902684172, 6150.231221097363, 6152.323244443108, 6220.564560480723, 6106.603070800384, 6123.25211838015, 6203.906941395882, 6210.254769427607, 6211.16149319845, 4890.804367825318, 4931.112399768004, 4956.90086198274, 4968.701877887338, 4987.0275296317595, 4409.338599210144, 4409.713368631029, 4412.205802955435, 4414.889627759332, 4476.216662084123, 5794.246747922827, 5828.038373668297, 5844.1818145459665, 5852.4440178183, 5908.438698323653, 6145.647957449443, 6149.84625384369, 6177.62934223939, 6189.50483961282, 6229.595076320024, 5704.486596206361, 5758.827235182951, 5801.477866991907, 5830.096335068071, 5842.152887831326, 5419.566434685215, 5419.566434685215, 5429.158480435539, 5436.972815135899, 5447.591430642718, 6131.877362452815, 6270.780522681775, 6299.559030867796, 6301.14934483848, 6315.810525684236, 6124.167343420691, 6142.775043102229, 6157.983932651063, 6166.506679465721, 6174.536909731878, 5290.206469029677, 6258.749650013949, 6260.311365749672, 6262.780802671885, 6265.310815810166, 6075.3317135115985, 6182.196314479089, 6192.689172527871, 6238.469730072995, 6248.687565621792, 5315.601329900351, 5709.857253568694, 6002.039673652194, 6126.94520042658, 6205.131281620486, 6185.288691800501, 6192.071189321638, 6194.34959329282, 6196.0705894116245, 6205.875882482415, 6057.939420605738, 6109.2974307955155, 6191.783287584913, 6208.596441231305, 6249.187605611241, 5975.760969561343, 6060.090986352083, 6072.743717294343, 6094.481969032682, 6123.14276001369, 6188.907166392598, 6193.040078787795, 6195.907061393992, 6212.316645169119, 6222.657753823583, 5908.202392676933, 5972.313183983897, 5975.402459754164, 6000.249968753906, 6029.397060293935], "value": 5680.289038151424, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.4967909176726, 529.5551736541305, 540.1870185371016, 544.8528897197757, 553.5958750112416, 30110.946116885912, 42049.78975105124, 43225.351619725654, 43243.567564324345, 45703.54296457034, 44589.77705111478, 44646.77676611613, 44960.77519612397, 45050.0, 45083.32375014369, 8763.956180219122, 9662.468564228982, 9841.55712992917, 9849.01509849013, 9854.113129818315, 8736.737897863075, 8964.282857371427, 8968.775780605505, 8971.282297416225, 9958.804943406802, 11134.99785019348, 11272.830907536394, 11826.7634647307, 12145.878541214563, 12148.696282592962, 11949.522019119226, 12097.93951030248, 12108.273503589724, 12120.33338166405, 12126.636200913987, 8733.301335893113, 11579.884201157965, 11792.174547781658, 11833.704157396134, 11913.22564033335, 30471.542926856117, 42304.576954230375, 42498.36252456216, 42591.36112958308, 42690.0, 3469.3581687387914, 3523.6828685418177, 3674.81625918703, 3834.827432765532, 3842.846286148551, 31605.0, 31683.524747128813, 32767.50848737271, 33014.33971320584, 33140.17149571256, 3323.833808309575, 3334.6331903490595, 3341.365140623274, 3380.5267262583257, 3453.8963831085107, 471100.0, 559005.0, 559658.0, 560238.1988090115, 561195.0, 63906.36093639097, 64864.0270395935, 64885.67557162184, 64903.675481623235, 64931.67534162299, 68437.65781171063, 68621.0, 68700.0, 68724.0, 68745.65627171833, 16807.327706891832, 17032.318707251572, 17061.573460663334, 17073.231704573325, 17078.658426831393, 13061.608151755463, 13130.0, 13133.60599182026, 13159.53941612039, 13160.868391316155, 16595.31038921981, 16834.410795622094, 17036.65926681484, 19459.318923837596, 20366.79633203649, 7255.8911616325795, 7842.882356764653, 8135.389845761473, 8136.471129376571, 8158.959205203936, 918.8392031394479, 941.924646028316, 950.1638558068993, 982.7936133411921, 1027.1782573940818, 28112.43775124486, 30386.85007697256, 31003.379932401225, 31746.206344841565, 32847.850325238505, 1027.409239687183, 1098.2367254758026, 1099.8515200447998, 1101.7410908436511, 1147.6098126636984, 16208.837911620969, 16262.593435164215, 16287.185640718153, 16325.91837040831, 16654.83345166557, 729171.0, 735101.0, 735928.0, 736292.0, 736391.0, 61298.0, 61852.0, 61862.69068654718, 61910.380896190465, 62179.0, 1375.113052081422, 1424.5441458733312, 1447.558494659118, 1449.9275036248357, 1451.056813071512, 25255.0, 30754.231144221572, 30812.383752325277, 30868.691313087482, 31579.684203158595, 52910.47089529209, 53116.46883531115, 53543.6614084651, 53546.99999999844, 53608.73195634153, 628449.0, 632439.0, 633302.0, 633696.8315158394, 634312.0, 23633.936472859106, 24606.138785142433, 27014.864925675247, 27139.72860271372, 27467.450650987266, 48718.53729615452, 48942.021159575896, 49421.76445588889, 49521.50478495169, 50007.0, 40415.1916961665, 40679.59320406876, 40725.796371017954, 41462.58537414708, 41742.37386439266, 39728.80135599303, 40080.799596003, 41151.5127483243, 41174.0, 41332.38001429921, 38074.23851523009, 38596.42105368478, 38737.418938715375, 38812.835614931595, 38844.0, 34340.969770906915, 34770.0, 35908.4613730789, 35958.82020589985, 35989.64010359863, 24063.186650038682, 24455.532668039974, 24538.386540336633, 25781.484370312864, 26212.475750485264], "value": 21033.922916482403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.4587535195799, 351.68699857127154, 374.4458201861244, 381.83008561190263, 403.45734986443244, 15721.292541835617, 16550.66898662026, 20168.092435840386, 20517.692234616494, 20810.687839682378, 21308.36074917755, 21392.517666343498, 21734.782652173475, 22098.889505552448, 22890.427739306484, 5302.973485132569, 5921.911171332434, 6109.480694141003, 6113.113598528211, 6159.538034647397, 4172.5410204877435, 4370.99467122562, 6011.098335249726, 6357.0, 6696.0, 6556.0, 6685.899711504307, 6695.263521012685, 6700.3969642732145, 6717.126773519436, 6473.255575608795, 6627.7680281190405, 6648.601083934979, 6653.367930046637, 6656.434203092743, 5044.394672639307, 5353.063213937584, 5368.00691872005, 5788.797392091299, 5789.855253618651, 13378.799318010238, 13601.979851511176, 13618.18290902549, 23403.297901062997, 26345.20964371053, 2390.486045500226, 2506.749325067497, 2620.790336773073, 2673.518766622007, 2981.806182598124, 10186.236032297607, 10316.948415257875, 10333.276670633059, 10404.21968352369, 10409.635662751767, 1805.5034865412117, 1827.6070644811296, 1828.7988321284647, 1846.3814622101613, 1847.6489467001227, 124256.0, 126803.0, 128378.0, 136307.0, 137118.0, 9415.717528474157, 11459.19785614999, 11498.96509314149, 11575.363355015386, 11709.833680769085, 14413.279336033365, 14514.927425362806, 14534.200618965848, 14549.272536373139, 14578.052426592238, 7714.614269286513, 8100.946876905965, 8635.395522313378, 8639.827203455896, 8657.783555411164, 8606.5696715164, 8639.95680021596, 8670.089640587774, 8733.475991440533, 8778.956105219433, 4525.185466616041, 4541.909161816745, 4547.295169248771, 4565.338025986213, 4638.582527572535, 1748.1696194307601, 1800.1395986781963, 1826.954326141857, 1966.8426525877896, 1982.762068551783, 883.5361435246547, 924.8531820542479, 928.2944961829058, 934.4299977014016, 984.4536282363214, 18107.45677629673, 21153.471163220736, 21351.572968540542, 21388.465288367603, 21408.57182856334, 966.4636126949611, 991.5339790298548, 992.5781542844262, 1009.9798004039878, 1019.4800651667703, 10671.466426678635, 11297.661070167907, 11526.88473115275, 11564.653060408202, 11566.248193867372, 572149.0, 605498.9725051346, 618998.9050054721, 632518.0, 633474.0, 79561.60219198867, 122164.0, 122437.0, 125068.0, 126195.0, 988.8862780780303, 989.4805227255605, 997.1623835977814, 1009.6718566465781, 1009.8586197932295, 16046.277917493975, 17593.472195834147, 19166.0, 19357.90321048442, 19440.61118777645, 50519.0, 52375.0, 52714.73642631762, 53344.73327633337, 54187.0, 75814.48371032438, 76289.61855190911, 76392.61803690947, 77927.0, 78346.29922204457, 7205.207427183111, 7558.546487210785, 7597.278258565428, 7638.50349727266, 7859.0, 12938.870611294144, 13390.732185356044, 13397.66505837362, 13590.252536110213, 13654.93172534131, 9419.340646154977, 11374.146938979444, 11586.0, 11604.245724027911, 11759.882401175879, 11308.151888608223, 11433.59982400612, 11500.597479088192, 11590.71023224426, 11795.764084718086, 10945.56217751313, 11662.70843228926, 11878.762424751629, 11895.524179032742, 12097.032237421161, 7572.886406703794, 7588.430867685056, 7664.5401275921395, 7671.0, 7735.0, 10898.400587967737, 10965.56137754481, 11552.480138393961, 11612.825807613062, 11621.941890290494], "value": 9579.929387148048, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1551.8060242469692, 1597.8641815445685, 1807.2228941555127, 1938.8642795004337, 1943.5529828139527, 8534.658613655454, 8832.028476867556, 8968.968568614611, 8994.86507702385, 8997.370184087116, 7050.259722729116, 7613.8477230455355, 7809.0, 8787.253083487894, 8802.64789408423, 2364.6807680963066, 2395.520895820834, 2396.8015992004, 2397.880105994693, 2397.9520409591796, 2361.6929799126087, 2380.1193558383334, 2397.2688330059327, 2402.8438151520095, 2403.1108489858775, 2112.334614596405, 2179.6512557990723, 2288.290629904735, 2367.538330025646, 2374.845635033726, 2350.4476448034698, 2374.1334412939327, 2402.0631953538136, 2402.6636270922, 2409.7469765674527, 2390.6772585700883, 2399.4841109161616, 2425.260295609838, 2425.757424257578, 2431.0, 8896.59965301563, 8976.910230897673, 8982.505962172077, 8983.505907175102, 8991.730248092568, 5188.844334669966, 5310.336207974004, 5337.359516857963, 5348.598855085884, 5377.085895397791, 3482.355764183634, 3583.9104022399647, 3639.636036396339, 3644.1071937375514, 3647.544056992876, 1068.471106802138, 1070.1706177712258, 1080.2978064258216, 1097.2812807611028, 1110.7056629993058, 11272.605458808903, 11448.313101213955, 11468.770624587461, 11481.368524731137, 11508.424578771112, 4475.48531918827, 4476.865694029184, 4696.154692155376, 4702.1065997461055, 4730.645201609893, 9298.74466946967, 10017.949910250401, 10025.749356266006, 10045.397276163454, 10050.346727462691, 4812.951870481321, 5224.216367544823, 5293.867653308698, 5296.549793267615, 5313.707746074003, 3904.6095390460728, 4542.841000564964, 4854.077725232195, 4854.271859221074, 4856.708597484162, 3810.65704086628, 3818.045488627843, 3847.8076096195077, 3891.727579069438, 3923.1761330120426, 1187.5427960235372, 1202.0563857371922, 1215.851020785367, 1225.9509619615114, 1233.7162452635764, 812.4312980913385, 908.4095338030334, 911.6809116809196, 979.2019504104132, 1001.2240513602017, 5885.911711324334, 5938.317093534212, 5946.494547963472, 6042.758289668364, 6148.538859585549, 1039.781645854364, 1047.450088703437, 1053.4364115198332, 1107.7064577886867, 1111.5776005118003, 4198.307279298942, 4290.656747460196, 4310.353446982977, 4379.978100109479, 4406.581374769392, 10771.03060724523, 10893.0, 11048.392338421463, 11084.61203857861, 11121.388323642115, 9810.0, 10168.440735759605, 10992.890071099346, 11004.523982103963, 11043.171762117874, 7749.690012399441, 8116.5941702914615, 8172.978377702787, 8246.422750407413, 8261.710840120568, 5767.8558036049435, 5882.588218824641, 5954.10688396744, 6154.753809847735, 6303.401176888188, 9587.760305992406, 9877.0, 9903.009699030039, 9918.05778451046, 9938.10557049869, 10152.39085654863, 10238.027387398186, 10244.385336879812, 10268.0, 10286.639967601099, 7442.367398771165, 7502.437317201341, 7504.399648028036, 7582.241775822374, 7593.278638529331, 8219.712310069119, 8324.25081742646, 8377.916220837713, 8389.035260944825, 8416.452930559493, 6205.441510264098, 6578.934210657832, 7446.962765186139, 7607.972923655457, 7611.581363024865, 7326.560406375635, 7450.776476705708, 7460.589667568119, 7570.9242907570215, 7597.6201189939175, 6775.593464392152, 7023.578585284899, 7032.648367581498, 7043.119610048744, 7075.575465471882, 4806.110869489155, 5076.746162691777, 5119.078565858182, 5134.075866344094, 5220.034293655686, 5078.9238161426865, 5137.537621613923, 5165.845024649267, 5178.275041494269, 5331.760070796901], "value": 4629.82768579565, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.29222719359944, 302.75930635145056, 309.5588785979978, 314.17215636797033, 315.6653946816376, 4895.922896962671, 4939.8024079036795, 5056.772445239963, 5268.288781014567, 5314.202869569566, 4722.05558888222, 4756.310335001423, 4768.380110585619, 5187.896242075156, 5247.793007608252, 3144.229663732389, 3920.647141757241, 4048.392741088838, 4056.492938382702, 4083.734557253769, 3975.07209003633, 3980.4716524791907, 4039.6775450268224, 4061.4110953911663, 4098.971009350375, 3515.9472607910902, 3519.0469289544294, 4013.3779264214095, 4081.755094694313, 4083.4079058536495, 3808.638179372955, 4010.659093977001, 4036.5761595032686, 4058.6753059755442, 4063.7155399122075, 3531.364354416204, 3543.255916257589, 3553.0813360785146, 3556.199855032623, 3577.373959557067, 4150.958490415087, 4325.632321252666, 5005.424376196747, 5118.820841270575, 5221.39953905302, 3809.9238015239816, 4571.245744452194, 4574.61115805157, 4603.7698115094445, 4627.038695235052, 4595.471520775087, 5506.64206826555, 5530.668159910419, 5542.812882592485, 5549.001179787637, 3157.857896394668, 3387.3902697514436, 3814.4659747635355, 3816.5229346331707, 3827.5981021992848, 5760.395158508338, 5787.33445653751, 5788.305403351583, 5816.418358164235, 5829.562782791265, 3519.788812671248, 3869.496965394481, 3879.991202287369, 3880.301545721797, 3891.8324502649093, 3464.549608550872, 3947.7236593438183, 3947.921041579152, 3957.4763715969557, 3961.425593288957, 4232.365145228179, 4522.886927826765, 4572.565606267399, 4578.702384345007, 4592.2422800238255, 4255.446791917031, 4819.831305904277, 5481.616286859881, 5581.469760372758, 5642.561146907512, 1007.5063219022626, 1105.9612913547987, 1295.8898493628146, 1335.592644243515, 1337.8461476930083, 826.7974346285199, 848.8811566380712, 891.6491515354244, 898.3711402018482, 913.0823522359985, 5137.306463627362, 5141.76862041209, 5156.20078887773, 5166.948330516722, 5178.964207158508, 4530.592246697747, 5161.458046905096, 5614.382417934024, 5628.607841667275, 5630.549556035589, 4856.83001094967, 5077.365329333833, 5079.492050794891, 5111.463296353903, 5120.487951204924, 2154.9030293636824, 2231.866088034723, 2330.4618951491934, 2474.8762561872195, 2531.962020569693, 3902.9609703903166, 4187.790610469525, 4408.6693497987135, 4912.533309335608, 4983.750812459362, 4414.227510185769, 4761.095561775318, 4784.952150478521, 4832.251001094836, 4861.368022157097, 4381.759003254788, 4422.7346359218545, 4868.488808675038, 4878.658493905393, 4885.313077807192, 3874.38009918423, 4364.6944713871, 4608.30875368684, 4636.884077898079, 4770.6183505318795, 4486.304622783343, 4506.35518035908, 4643.512431194676, 4670.439547254351, 4750.453697824656, 4994.400671919393, 5394.487523685244, 5608.691521966331, 5953.791617293374, 6073.969630151821, 3825.6365645263654, 3867.781648780625, 3894.805259736945, 3915.314819906448, 3916.5300163980505, 5175.327207463006, 5411.161270003156, 5883.970580147072, 5917.526597872074, 5918.23063001807, 4739.905201896012, 5105.957616953248, 5421.9186712200135, 5517.972410137923, 5641.887162256814, 5585.497305242548, 5590.692511911884, 5605.0, 5612.0, 5651.350094739158, 5177.456367081402, 5494.230807687007, 5508.421615730451, 5514.36584792754, 5573.191887176417, 4611.631069514364, 5265.710385928657, 5369.436209197978, 5618.297712785902, 5625.943740562706, 5485.491489840326, 5487.067198576172, 5499.532539734168, 5509.724513774215, 5515.7732726353925], "value": 3913.9111430100784, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.2711025419454, 838.1827717974971, 851.5188918261182, 852.8294341131775, 868.3443999780167, 13806.516771912973, 14818.407263709452, 14828.961972661918, 14850.1832399218, 15832.762508562351, 13212.735745285088, 16375.918120409411, 16396.426125085625, 16400.75398869018, 16499.34002639896, 695.7843068648709, 759.2028370211284, 879.3141349747192, 911.9452832830051, 914.8399030169694, 713.1050531582856, 762.6034462079715, 870.1516021878681, 902.7968707040897, 904.2268860124598, 932.1703683721494, 932.2728271947891, 936.5925822267302, 939.5818860607033, 941.1764705882352, 732.5531425830239, 914.5335878701843, 935.3452583191793, 936.8688383626263, 936.9531523423801, 696.4324075878194, 696.4846013949691, 697.2817997462622, 698.0053423870963, 699.4509310191476, 15017.023893447, 15042.0, 15068.020578662461, 15086.54740357791, 15113.3199006045, 14000.92999535006, 14149.632764845015, 14150.575482735534, 14233.217173055478, 14348.210848403334, 13578.185308881499, 13606.115602485806, 13759.656008599764, 13821.170729756146, 14189.858101418957, 4673.369095172142, 4706.7646617669325, 4728.976355118237, 4782.545658162469, 4849.151398505247, 127637.0, 128507.0, 130282.0, 130581.34709326488, 131191.34404327918, 10997.395143267036, 11012.669619911416, 11119.388433636066, 11162.0, 11655.475503602358, 21505.924703764747, 21642.35072947814, 21659.133634654754, 21669.458263543536, 21688.34934951954, 7931.444798864024, 7960.601969901481, 8012.238837310446, 8044.074931382849, 8199.71301004462, 4393.1872603568445, 4456.821727130879, 4477.462704475483, 4527.524609915978, 4634.443866736014, 3450.3789317922515, 3543.982280088583, 3569.4824250483534, 3574.660407261306, 3584.444411116281, 639.0286764118514, 653.2194028136331, 657.1653999420708, 663.2670898657011, 671.2951401028997, 606.9635821850703, 622.8474023864183, 640.3638703113589, 647.4270270810367, 653.323809856796, 9109.274380602179, 9192.0, 9322.813543729224, 9475.336726429221, 9749.902500975042, 618.1994317359031, 620.385818040137, 621.5493767018966, 639.3957709964133, 640.4556127291858, 3837.865674701372, 4447.488538818013, 4488.842890498817, 4500.887477813081, 4520.141173177086, 126452.0, 156012.0, 169974.60050798915, 170272.44591331139, 170517.0, 36702.81648591793, 83232.0, 144159.0, 145125.5487445133, 148906.0, 124603.37698311632, 124957.0, 127205.36397317954, 127404.0, 130059.34970325087, 8631.611577478903, 8672.132786721277, 8683.435576687496, 8712.390132690904, 8866.601002954754, 67603.0, 70165.29834701588, 70423.59152817017, 70740.58518829492, 71776.0, 97244.0, 98826.01173988456, 105838.47080764547, 105863.47068264609, 107329.0, 11628.18602697803, 11631.59289424866, 11909.583164589198, 12134.514619415124, 12217.93891030539, 12921.612351629467, 13024.739505210033, 13064.085514013928, 13425.0602457827, 13425.395857186255, 10353.382820572871, 12020.63938081859, 12031.15781895259, 12152.513899443924, 12563.371831408576, 11793.351365674627, 12083.82744222674, 12205.328706920849, 12537.435815388511, 12542.561010364594, 11795.882041179479, 11859.169858110195, 11863.406829658656, 12200.084993625691, 12239.0, 7940.483868548526, 10092.798144037224, 10138.341007834468, 10473.31923424975, 10528.263021588415, 8798.296136308953, 9387.014363491735, 9618.580418498497, 10093.646722364681, 10099.79800403973], "value": 7859.385518394581, "unit": "score"}], "name": "dromaeo_css", "value": 7391.136385263309, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 112.0, 111.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 106.0, 161.0, 106.0, 107.0, 150.0, 136.0, 151.0, 107.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 195.0, 259.0, 198.0, 185.0, 182.0, 185.0, 184.0, 185.0, 201.0], "value": 196.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 58.0, 89.0, 57.0, 57.0, 65.0, 63.0, 82.0, 56.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 93.0, 93.0, 57.0, 66.0, 79.0, 130.0, 100.0, 98.0, 137.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 94.0, 89.0, 92.0, 110.0, 93.0, 89.0, 92.0, 88.0], "value": 92.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 107.0, 148.0, 149.0, 95.0, 136.0, 149.0, 96.0], "value": 116.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 133.0, 87.0, 86.0, 87.0, 86.0, 86.0, 83.0, 84.0, 84.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 53.0, 52.0, 54.0, 51.0, 51.0, 52.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 76.0, 77.0, 78.0, 74.0, 72.0, 71.0, 76.0, 73.0, 75.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 83.0, 92.0, 101.0, 82.0, 86.0, 83.0, 83.0, 91.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 146.0, 150.0, 134.0, 139.0, 126.0, 158.0, 207.0, 145.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 163.0, 164.0, 178.0, 225.0, 170.0, 165.0, 165.0, 161.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 67.0, 65.0, 66.0, 66.0, 65.0, 66.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1499.3999999999999, "alertThreshold": 2.0}]} 10:00:31 INFO - Return code: 0 10:00:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:00:31 INFO - # TBPL SUCCESS # 10:00:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:00:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:00:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:00:31 INFO - 2016-07-19 10:00:31.705 screenresolution[6329:106926] starting screenresolution argv=screenresolution get 10:00:31 INFO - 2016-07-19 10:00:31.707 screenresolution[6329:106926] Display 0: 1600x1200x32@60 10:00:31 INFO - 2016-07-19 10:00:31.714 screenresolution[6330:106929] starting screenresolution argv=screenresolution list 10:00:31 INFO - Available Modes on Display 0 10:00:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:00:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:00:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:00:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:00:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:00:31 INFO - Intel Iris: 10:00:31 INFO - Chipset Model: Intel Iris 10:00:31 INFO - Type: GPU 10:00:31 INFO - Bus: Built-In 10:00:31 INFO - VRAM (Dynamic, Max): 1536 MB 10:00:31 INFO - Vendor: Intel (0x8086) 10:00:31 INFO - Device ID: 0x0a2e 10:00:31 INFO - Revision ID: 0x0009 10:00:31 INFO - Displays: 10:00:31 INFO - Display: 10:00:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:00:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:00:31 INFO - Display Serial Number: 1600x1200 60 10:00:31 INFO - Main Display: Yes 10:00:31 INFO - Mirror: Off 10:00:31 INFO - Online: Yes 10:00:31 INFO - Rotation: Supported 10:00:31 INFO - Return code: 0 10:00:31 INFO - Running post-action listener: _resource_record_post_action 10:00:31 INFO - ##### 10:00:31 INFO - ##### Finished run-tests step (success) 10:00:31 INFO - ##### 10:00:31 INFO - Running post-run listener: _resource_record_post_run 10:00:32 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 1551872; Write bytes: 702039040; Read time: 25; Write time: 2118 10:00:32 INFO - TinderboxPrint: CPU usage
53.6% 10:00:32 INFO - TinderboxPrint: I/O read bytes / time
1,551,872 / 25 10:00:32 INFO - TinderboxPrint: I/O write bytes / time
702,039,040 / 2,118 10:00:32 INFO - TinderboxPrint: CPU idle
2,252.8 (46.4%) 10:00:32 INFO - TinderboxPrint: CPU system
974.2 (20.1%) 10:00:32 INFO - TinderboxPrint: CPU user
1,626.6 (33.5%) 10:00:32 INFO - TinderboxPrint: Swap in / out
410,800,128 / 0 10:00:32 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 397038080; Write bytes: 391194112; Read time: 30317; Write time: 680 10:00:32 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1551872; Write bytes: 291122688; Read time: 25; Write time: 1395 10:00:32 INFO - Running post-run listener: _upload_blobber_files 10:00:32 INFO - Blob upload gear active. 10:00:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:00:32 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 10:00:32 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'] 10:00:32 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 10:00:32 INFO - (blobuploader) - INFO - Open directory for files ... 10:00:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:00:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:00:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:34 INFO - (blobuploader) - INFO - Done attempting. 10:00:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:00:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:00:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:35 INFO - (blobuploader) - INFO - Done attempting. 10:00:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:00:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:00:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:39 INFO - (blobuploader) - INFO - Done attempting. 10:00:39 INFO - (blobuploader) - INFO - Iteration through files over. 10:00:39 INFO - Return code: 0 10:00:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:00:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:00:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3b94c0fd993698ac19cc9df3cc47e1d14f0535b5673956412c2e4809d8c9604b5f4cb617f11ed33b1cda44d8034d52d7b9ce7001d66969ab057394fbbb33399", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b10ad2653c01e72a22a8a8ab09a7fbd15619bc2486d50381db6b44e40ad51a98be21ada4fdca9c102a345cf68ba3af6a7e9ac544c67830322db268507471a87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/29f3c4f8c850e429c85590e697b4cb2e719b03c72bcc665a4248a5cb4cd00ca3173521bafffe35c6089458a3f38b783e6052df89c232ebc72b2e4c3fd0575662"} 10:00:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:00:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:00:39 INFO - Contents: 10:00:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3b94c0fd993698ac19cc9df3cc47e1d14f0535b5673956412c2e4809d8c9604b5f4cb617f11ed33b1cda44d8034d52d7b9ce7001d66969ab057394fbbb33399", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b10ad2653c01e72a22a8a8ab09a7fbd15619bc2486d50381db6b44e40ad51a98be21ada4fdca9c102a345cf68ba3af6a7e9ac544c67830322db268507471a87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/29f3c4f8c850e429c85590e697b4cb2e719b03c72bcc665a4248a5cb4cd00ca3173521bafffe35c6089458a3f38b783e6052df89c232ebc72b2e4c3fd0575662"} 10:00:39 INFO - Running post-run listener: copy_logs_to_upload_dir 10:00:39 INFO - Copying logs to upload dir... 10:00:39 INFO - mkdir: /builds/slave/test/build/upload/logs 10:00:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.605504 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-19 10:00:39.831055) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 10:00:39.835155) ========= 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.gkzy68R1dc/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.7slfFytNUD/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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/c3b94c0fd993698ac19cc9df3cc47e1d14f0535b5673956412c2e4809d8c9604b5f4cb617f11ed33b1cda44d8034d52d7b9ce7001d66969ab057394fbbb33399", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b10ad2653c01e72a22a8a8ab09a7fbd15619bc2486d50381db6b44e40ad51a98be21ada4fdca9c102a345cf68ba3af6a7e9ac544c67830322db268507471a87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/29f3c4f8c850e429c85590e697b4cb2e719b03c72bcc665a4248a5cb4cd00ca3173521bafffe35c6089458a3f38b783e6052df89c232ebc72b2e4c3fd0575662"} build_url:https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011517 build_url: 'https://queue.taskcluster.net/v1/task/d3Kw9gItRrukvaJFSoctow/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/c3b94c0fd993698ac19cc9df3cc47e1d14f0535b5673956412c2e4809d8c9604b5f4cb617f11ed33b1cda44d8034d52d7b9ce7001d66969ab057394fbbb33399", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b10ad2653c01e72a22a8a8ab09a7fbd15619bc2486d50381db6b44e40ad51a98be21ada4fdca9c102a345cf68ba3af6a7e9ac544c67830322db268507471a87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/29f3c4f8c850e429c85590e697b4cb2e719b03c72bcc665a4248a5cb4cd00ca3173521bafffe35c6089458a3f38b783e6052df89c232ebc72b2e4c3fd0575662"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 10:00:39.868777) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 10:00:39.869113) ========= 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.gkzy68R1dc/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.7slfFytNUD/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.005432 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 10:00:39.918585) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 10:00:39.918905) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 10:00:39.919272) ========= ========= Total master_lag: 0.17 =========