builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0214 starttime: 1464881681.3 results: success (0) buildid: 20160602074145 builduid: 0f216bf1ac0144abb9d073f5a1e7d3ed revision: a47a4d3877eb3a732d7578d77ade1142b1b941e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:41.301256) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:41.301756) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:41.302058) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:41.324154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:41.324499) ========= 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.IMTiArOwwW/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.ZiKmlITtgd/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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-06-02 08:34:41-- 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% 18.6M=0.001s 2016-06-02 08:34:41 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116230 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:41.460773) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:41.461100) ========= 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.IMTiArOwwW/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.ZiKmlITtgd/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.026025 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:41.509727) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 08:34:41.510085) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a47a4d3877eb3a732d7578d77ade1142b1b941e7 --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 a47a4d3877eb3a732d7578d77ade1142b1b941e7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IMTiArOwwW/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.ZiKmlITtgd/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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-06-02 08:34:41,590 truncating revision to first 12 chars 2016-06-02 08:34:41,590 Setting DEBUG logging. 2016-06-02 08:34:41,590 attempt 1/10 2016-06-02 08:34:41,590 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a47a4d3877eb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 08:34:42,652 unpacking tar archive at: fx-team-a47a4d3877eb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.312802 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 08:34:42.845561) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:42.845983) ========= script_repo_revision: a47a4d3877eb3a732d7578d77ade1142b1b941e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:42.846434) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:42.846722) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 08:34:42.863599) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-06-02 08:34:42.864003) ========= /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.IMTiArOwwW/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.ZiKmlITtgd/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:34:43 INFO - MultiFileLogger online at 20160602 08:34:43 in /builds/slave/test 08:34:43 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 08:34:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:34:43 INFO - {'append_to_log': False, 08:34:43 INFO - 'base_work_dir': '/builds/slave/test', 08:34:43 INFO - 'blob_upload_branch': 'Fx-Team', 08:34:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:34:43 INFO - 'branch': 'Fx-Team', 08:34:43 INFO - 'buildbot_json_path': 'buildprops.json', 08:34:43 INFO - 'config_files': ('talos/mac_config.py',), 08:34:43 INFO - 'default_actions': ('clobber', 08:34:43 INFO - 'read-buildbot-config', 08:34:43 INFO - 'download-and-extract', 08:34:43 INFO - 'populate-webroot', 08:34:43 INFO - 'create-virtualenv', 08:34:43 INFO - 'install', 08:34:43 INFO - 'run-tests'), 08:34:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:34:43 INFO - 'download_minidump_stackwalk': True, 08:34:43 INFO - 'download_symbols': 'ondemand', 08:34:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:34:43 INFO - 'tooltool.py': '/tools/tooltool.py', 08:34:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:34:43 INFO - '/tools/misc-python/virtualenv.py')}, 08:34:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:34:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:34:43 INFO - 'installer_path': 'installer.exe', 08:34:43 INFO - 'log_level': 'info', 08:34:43 INFO - 'log_name': 'talos', 08:34:43 INFO - 'log_to_console': True, 08:34:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:34:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:34:43 INFO - 'opt_config_files': (), 08:34:43 INFO - 'pip_index': False, 08:34:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:34:43 INFO - 'cmd': ('bash', 08:34:43 INFO - '-c', 08:34:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:34:43 INFO - 'enabled': True, 08:34:43 INFO - 'halt_on_failure': False, 08:34:43 INFO - 'name': 'check_screen_resolution'},), 08:34:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:34:43 INFO - 'cmd': ('bash', 08:34:43 INFO - '-c', 08:34:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:34:43 INFO - 'enabled': True, 08:34:43 INFO - 'halt_on_failure': False, 08:34:43 INFO - 'name': 'check_screen_resolution'},), 08:34:43 INFO - 'run_cmd_checks_enabled': True, 08:34:43 INFO - 'sps_profile': False, 08:34:43 INFO - 'sps_profile_interval': 0, 08:34:43 INFO - 'suite': 'dromaeojs', 08:34:43 INFO - 'system_bits': '32', 08:34:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:34:43 INFO - 'title': 't-yosemite-r7-0214', 08:34:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:34:43 INFO - 'use_talos_json': True, 08:34:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:34:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:34:43 INFO - 'work_dir': 'build'} 08:34:43 INFO - ##### 08:34:43 INFO - ##### Running clobber step. 08:34:43 INFO - ##### 08:34:43 INFO - Running pre-action listener: _resource_record_pre_action 08:34:43 INFO - Running main action method: clobber 08:34:43 INFO - rmtree: /builds/slave/test/build 08:34:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:34:45 INFO - Running post-action listener: _resource_record_post_action 08:34:45 INFO - ##### 08:34:45 INFO - ##### Finished clobber step (success) 08:34:45 INFO - ##### 08:34:45 INFO - ##### 08:34:45 INFO - ##### Running read-buildbot-config step. 08:34:45 INFO - ##### 08:34:45 INFO - Running pre-action listener: _resource_record_pre_action 08:34:45 INFO - Running main action method: read_buildbot_config 08:34:45 INFO - Using buildbot properties: 08:34:45 INFO - { 08:34:45 INFO - "project": "", 08:34:45 INFO - "product": "firefox", 08:34:45 INFO - "script_repo_revision": "production", 08:34:45 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:34:45 INFO - "repository": "", 08:34:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:34:45 INFO - "buildid": "20160602074145", 08:34:45 INFO - "pgo_build": "False", 08:34:45 INFO - "basedir": "/builds/slave/test", 08:34:45 INFO - "buildnumber": 52, 08:34:45 INFO - "slavename": "t-yosemite-r7-0214", 08:34:45 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:34:45 INFO - "platform": "yosemite_r7", 08:34:45 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:34:45 INFO - "branch": "fx-team", 08:34:45 INFO - "repo_path": "integration/fx-team", 08:34:45 INFO - "revision": "a47a4d3877eb3a732d7578d77ade1142b1b941e7", 08:34:45 INFO - "stage_platform": "macosx64", 08:34:45 INFO - "builduid": "0f216bf1ac0144abb9d073f5a1e7d3ed", 08:34:45 INFO - "slavebuilddir": "test" 08:34:45 INFO - } 08:34:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:34:45 INFO - Running post-action listener: _resource_record_post_action 08:34:45 INFO - ##### 08:34:45 INFO - ##### Finished read-buildbot-config step (success) 08:34:45 INFO - ##### 08:34:45 INFO - ##### 08:34:45 INFO - ##### Running download-and-extract step. 08:34:45 INFO - ##### 08:34:45 INFO - Running pre-action listener: _resource_record_pre_action 08:34:45 INFO - Running main action method: download_and_extract 08:34:45 INFO - mkdir: /builds/slave/test/build/tests 08:34:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:34:45 INFO - https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:34:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:34:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:34:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:34:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 08:34:46 INFO - Downloaded 1199 bytes. 08:34:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:34:46 INFO - Using the following test package requirements: 08:34:46 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:34:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:34:46 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:34:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:34:46 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:34:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:34:46 INFO - u'jsshell-mac.zip'], 08:34:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:34:46 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:34:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:34:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:34:46 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:34:46 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:34:46 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:34:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:34:46 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:34:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:34:46 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:34:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:34:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:34:46 INFO - https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:34:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:34:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:34:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:34:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:34:47 INFO - Downloaded 18713569 bytes. 08:34:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:34:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:34:50 INFO - Return code: 0 08:34:50 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:34:50 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')]} 08:34:50 INFO - https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:34:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:34:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:34:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:34:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:34:51 INFO - Downloaded 18713569 bytes. 08:34:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:34:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:34:54 INFO - Return code: 0 08:34:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:34:54 INFO - https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:34:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:34:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:34:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:34:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:34:55 INFO - Downloaded 10834055 bytes. 08:34:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:34:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:34:56 INFO - Return code: 0 08:34:56 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')]} 08:34:56 INFO - https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:34:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:34:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:34:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:34:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 08:34:59 INFO - Downloaded 111071621 bytes. 08:34:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:34:59 INFO - mkdir: /builds/slave/test/properties 08:34:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:34:59 INFO - Writing to file /builds/slave/test/properties/build_url 08:34:59 INFO - Contents: 08:34:59 INFO - build_url:https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:35:01 INFO - Running post-action listener: _resource_record_post_action 08:35:01 INFO - Running post-action listener: set_extra_try_arguments 08:35:01 INFO - ##### 08:35:01 INFO - ##### Finished download-and-extract step (success) 08:35:01 INFO - ##### 08:35:01 INFO - ##### 08:35:01 INFO - ##### Running populate-webroot step. 08:35:01 INFO - ##### 08:35:01 INFO - Running pre-action listener: _resource_record_pre_action 08:35:01 INFO - Running main action method: populate_webroot 08:35:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:35:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:35:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:35:01 INFO - '--tppagecycles', 08:35:01 INFO - '7'], 08:35:01 INFO - 'tests': ['tsvgm']}}, 08:35:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:35:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:35:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:35:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:35:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:01 INFO - 'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:35:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:01 INFO - 'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['damp', 'tps']}, 08:35:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:01 INFO - 'tests': ['damp', 'tps']}, 08:35:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['dromaeo_dom']}, 08:35:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:35:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['a11yr', 08:35:01 INFO - 'ts_paint', 08:35:01 INFO - 'tpaint', 08:35:01 INFO - 'sessionrestore', 08:35:01 INFO - 'sessionrestore_no_auto_restore', 08:35:01 INFO - 'tabpaint']}, 08:35:01 INFO - 'other-e10s': {'tests': ['a11yr', 08:35:01 INFO - 'ts_paint', 08:35:01 INFO - 'tpaint', 08:35:01 INFO - 'sessionrestore', 08:35:01 INFO - 'sessionrestore_no_auto_restore', 08:35:01 INFO - 'tabpaint']}, 08:35:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:35:01 INFO - 'ts_paint', 08:35:01 INFO - 'tpaint', 08:35:01 INFO - 'sessionrestore', 08:35:01 INFO - 'sessionrestore_no_auto_restore', 08:35:01 INFO - 'tabpaint']}, 08:35:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:35:01 INFO - 'ts_paint', 08:35:01 INFO - 'tpaint', 08:35:01 INFO - 'sessionrestore', 08:35:01 INFO - 'sessionrestore_no_auto_restore', 08:35:01 INFO - 'tabpaint']}, 08:35:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['a11yr', 08:35:01 INFO - 'ts_paint', 08:35:01 INFO - 'tpaint', 08:35:01 INFO - 'sessionrestore', 08:35:01 INFO - 'sessionrestore_no_auto_restore', 08:35:01 INFO - 'tabpaint']}, 08:35:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['a11yr', 08:35:01 INFO - 'ts_paint', 08:35:01 INFO - 'tpaint', 08:35:01 INFO - 'sessionrestore', 08:35:01 INFO - 'sessionrestore_no_auto_restore', 08:35:01 INFO - 'tabpaint']}, 08:35:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['tsvgx', 08:35:01 INFO - 'tsvgr_opacity', 08:35:01 INFO - 'tart', 08:35:01 INFO - 'tscrollx', 08:35:01 INFO - 'cart']}, 08:35:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:35:01 INFO - 'tsvgr_opacity', 08:35:01 INFO - 'tart', 08:35:01 INFO - 'tscrollx', 08:35:01 INFO - 'cart']}, 08:35:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:01 INFO - 'talos_options': ['--disable-e10s'], 08:35:01 INFO - 'tests': ['tp5o']}, 08:35:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:01 INFO - 'tests': ['tp5o']}, 08:35:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:01 INFO - 'talos_options': ['--disable-e10s', 08:35:01 INFO - '--xperf_path', 08:35:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:01 INFO - 'tests': ['tp5n']}, 08:35:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:35:01 INFO - 'talos_options': ['--xperf_path', 08:35:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:35:01 INFO - 'tests': ['tp5n']}}, 08:35:01 INFO - 'talos.zip': {'path': '', 08:35:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:35:01 INFO - Running post-action listener: _resource_record_post_action 08:35:01 INFO - ##### 08:35:01 INFO - ##### Finished populate-webroot step (success) 08:35:01 INFO - ##### 08:35:01 INFO - ##### 08:35:01 INFO - ##### Running create-virtualenv step. 08:35:01 INFO - ##### 08:35:01 INFO - Running pre-action listener: _resource_record_pre_action 08:35:01 INFO - Running main action method: create_virtualenv 08:35:01 INFO - Creating virtualenv /builds/slave/test/build/venv 08:35:01 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 08:35:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:35:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:35:01 INFO - Using real prefix '/tools/python27' 08:35:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:35:02 INFO - Installing distribute.............................................................................................................................................................................................done. 08:35:04 INFO - Installing pip.................done. 08:35:04 INFO - Return code: 0 08:35:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10538ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105394570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ad3593ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, '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.IMTiArOwwW/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.ZiKmlITtgd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:35:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:35:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:35:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:04 INFO - 'HOME': '/Users/cltbld', 08:35:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:04 INFO - 'LOGNAME': 'cltbld', 08:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:04 INFO - 'MOZ_NO_REMOTE': '1', 08:35:04 INFO - 'NO_EM_RESTART': '1', 08:35:04 INFO - 'PAGER': '/bin/cat', 08:35:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:04 INFO - 'PWD': '/builds/slave/test', 08:35:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:04 INFO - 'SHELL': '/bin/bash', 08:35:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:35:04 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:35:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:04 INFO - 'USER': 'cltbld', 08:35:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:04 INFO - 'XPC_FLAGS': '0x0', 08:35:04 INFO - 'XPC_SERVICE_NAME': '0', 08:35:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:05 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 08:35:05 INFO - Cleaning up... 08:35:05 INFO - Return code: 0 08:35:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:05 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 0x10538ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105394570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ad3593ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, '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.IMTiArOwwW/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.ZiKmlITtgd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:35:05 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 08:35:05 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 08:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:05 INFO - 'HOME': '/Users/cltbld', 08:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:05 INFO - 'LOGNAME': 'cltbld', 08:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:05 INFO - 'MOZ_NO_REMOTE': '1', 08:35:05 INFO - 'NO_EM_RESTART': '1', 08:35:05 INFO - 'PAGER': '/bin/cat', 08:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:05 INFO - 'PWD': '/builds/slave/test', 08:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:05 INFO - 'SHELL': '/bin/bash', 08:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:35:05 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:05 INFO - 'USER': 'cltbld', 08:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:05 INFO - 'XPC_FLAGS': '0x0', 08:35:05 INFO - 'XPC_SERVICE_NAME': '0', 08:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:05 INFO - Downloading/unpacking psutil>=3.1.1 08:35: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 08:35: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 08:35: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 08:35: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 08:35: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 08:35: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 08:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:35:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:08 INFO - Installing collected packages: psutil 08:35:08 INFO - Running setup.py install for psutil 08:35:08 INFO - building 'psutil._psutil_osx' extension 08:35:08 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 08:35:09 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 08:35:09 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 08:35:09 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 08:35:09 INFO - building 'psutil._psutil_posix' extension 08:35:09 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 08:35:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:35:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:35:09 INFO - ^ 08:35:09 INFO - 1 warning generated. 08:35:09 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 08:35:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:35:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:35:09 INFO - Successfully installed psutil 08:35:09 INFO - Cleaning up... 08:35:09 INFO - Return code: 0 08:35:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:35:09 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')]} 08:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:09 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')]} 08:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:09 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 0x10538ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105394570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ad3593ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, '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.IMTiArOwwW/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.ZiKmlITtgd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:35:09 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 08:35:09 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 08:35:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:35:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:09 INFO - 'HOME': '/Users/cltbld', 08:35:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:09 INFO - 'LOGNAME': 'cltbld', 08:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:09 INFO - 'MOZ_NO_REMOTE': '1', 08:35:09 INFO - 'NO_EM_RESTART': '1', 08:35:09 INFO - 'PAGER': '/bin/cat', 08:35:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:09 INFO - 'PWD': '/builds/slave/test', 08:35:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:09 INFO - 'SHELL': '/bin/bash', 08:35:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:35:09 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:35:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:09 INFO - 'USER': 'cltbld', 08:35:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:09 INFO - 'XPC_FLAGS': '0x0', 08:35:09 INFO - 'XPC_SERVICE_NAME': '0', 08:35:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:35:09 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 08:35:09 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 08:35:09 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 08:35:09 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 08:35:09 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 08:35:09 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 08:35:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:35:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:35:12 INFO - Installing collected packages: mozsystemmonitor 08:35:12 INFO - Running setup.py install for mozsystemmonitor 08:35:12 INFO - Successfully installed mozsystemmonitor 08:35:12 INFO - Cleaning up... 08:35:12 INFO - Return code: 0 08:35:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:35: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')]} 08:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35: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')]} 08:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:12 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 0x10538ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105394570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ad3593ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, '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.IMTiArOwwW/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.ZiKmlITtgd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:35:12 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 08:35:12 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 08:35:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:35:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:12 INFO - 'HOME': '/Users/cltbld', 08:35:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:12 INFO - 'LOGNAME': 'cltbld', 08:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:12 INFO - 'MOZ_NO_REMOTE': '1', 08:35:12 INFO - 'NO_EM_RESTART': '1', 08:35:12 INFO - 'PAGER': '/bin/cat', 08:35:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:12 INFO - 'PWD': '/builds/slave/test', 08:35:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:12 INFO - 'SHELL': '/bin/bash', 08:35:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:35:12 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:35:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:12 INFO - 'USER': 'cltbld', 08:35:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:12 INFO - 'XPC_FLAGS': '0x0', 08:35:12 INFO - 'XPC_SERVICE_NAME': '0', 08:35:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:12 INFO - Downloading/unpacking blobuploader==1.2.4 08:35:12 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 08:35:12 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 08:35:12 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 08:35:12 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 08:35:12 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 08:35:12 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 08:35:15 INFO - Downloading blobuploader-1.2.4.tar.gz 08:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:35:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:35:15 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 08:35:15 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 08:35:15 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 08:35:15 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 08:35:15 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 08:35:15 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 08:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:35:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:35:16 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 08:35:16 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 08:35:16 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 08:35:16 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 08:35:16 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 08:35:16 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 08:35:16 INFO - Downloading docopt-0.6.1.tar.gz 08:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:35:16 INFO - Installing collected packages: blobuploader, requests, docopt 08:35:16 INFO - Running setup.py install for blobuploader 08:35:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:35:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:35:16 INFO - Running setup.py install for requests 08:35:17 INFO - Running setup.py install for docopt 08:35:17 INFO - Successfully installed blobuploader requests docopt 08:35:17 INFO - Cleaning up... 08:35:17 INFO - Return code: 0 08:35:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35: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')]} 08:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35: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')]} 08:35:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:17 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 0x10538ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105394570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ad3593ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, '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.IMTiArOwwW/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.ZiKmlITtgd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:35:17 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 08:35:17 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 08:35:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:35:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:17 INFO - 'HOME': '/Users/cltbld', 08:35:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:17 INFO - 'LOGNAME': 'cltbld', 08:35:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:17 INFO - 'MOZ_NO_REMOTE': '1', 08:35:17 INFO - 'NO_EM_RESTART': '1', 08:35:17 INFO - 'PAGER': '/bin/cat', 08:35:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:17 INFO - 'PWD': '/builds/slave/test', 08:35:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:17 INFO - 'SHELL': '/bin/bash', 08:35:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:35:17 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:35:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:17 INFO - 'USER': 'cltbld', 08:35:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:17 INFO - 'XPC_FLAGS': '0x0', 08:35:17 INFO - 'XPC_SERVICE_NAME': '0', 08:35:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:17 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-YZgTY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-oa7KZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-RDFI_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-50ZW9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-uxsRCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-sxSaqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-V1c68H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-WaGNyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-EQ0pLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-6FEoNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:18 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-fRiKLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-eSZ2xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-O5JHf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-lMCF_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-XvSejQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-6fVsWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:19 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Hrmciu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:35:19 INFO - Running setup.py install for manifestparser 08:35:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:35:19 INFO - Running setup.py install for mozcrash 08:35:19 INFO - Running setup.py install for mozdebug 08:35:20 INFO - Running setup.py install for mozdevice 08:35:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:35:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:35:20 INFO - Running setup.py install for mozfile 08:35:20 INFO - Running setup.py install for mozhttpd 08:35:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:35:20 INFO - Running setup.py install for mozinfo 08:35:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:35:20 INFO - Running setup.py install for mozInstall 08:35:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:35:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:35:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:35:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:35:20 INFO - Running setup.py install for mozleak 08:35:21 INFO - Running setup.py install for mozlog 08:35:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:35:21 INFO - Running setup.py install for moznetwork 08:35:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:35:21 INFO - Running setup.py install for mozprocess 08:35:21 INFO - Running setup.py install for mozprofile 08:35:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:35:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:35:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:35:21 INFO - Running setup.py install for mozrunner 08:35:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:35:21 INFO - Running setup.py install for mozscreenshot 08:35:21 INFO - Running setup.py install for moztest 08:35:22 INFO - Running setup.py install for mozversion 08:35:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:35:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:35:22 INFO - Cleaning up... 08:35:22 INFO - Return code: 0 08:35:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:35:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:22 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 0x10538ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105394570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ad3593ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, '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.IMTiArOwwW/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.ZiKmlITtgd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:35:22 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 08:35:22 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 08:35:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:35:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:22 INFO - 'HOME': '/Users/cltbld', 08:35:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:22 INFO - 'LOGNAME': 'cltbld', 08:35:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:22 INFO - 'MOZ_NO_REMOTE': '1', 08:35:22 INFO - 'NO_EM_RESTART': '1', 08:35:22 INFO - 'PAGER': '/bin/cat', 08:35:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:22 INFO - 'PWD': '/builds/slave/test', 08:35:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:22 INFO - 'SHELL': '/bin/bash', 08:35:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:35:22 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:35:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:22 INFO - 'USER': 'cltbld', 08:35:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:22 INFO - 'XPC_FLAGS': '0x0', 08:35:22 INFO - 'XPC_SERVICE_NAME': '0', 08:35:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:35:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-gnAQTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:35:22 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)) 08:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:35:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-x_gzyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:35:22 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)) 08:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:35:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-eaLTj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:35:22 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)) 08:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:35:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-HY5FdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:35:22 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)) 08:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:35:22 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-hOgiIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:35:23 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)) 08:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:35:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-F1Z0Si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:35:23 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)) 08:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:35:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-5ecYIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:35:23 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)) 08:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:35:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-SINnMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:35:23 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)) 08:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:35:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-lZN2iV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:35:23 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)) 08:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:35:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-poaBYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:35:23 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)) 08:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:35:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Bt9ygX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:35:23 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)) 08:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:35:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-J6JaCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:35:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:35:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-JmcjAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:35:23 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)) 08:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:35:23 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-BW54zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:35:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:24 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-4mcz1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:35:24 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)) 08:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:35:24 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-SESoEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:35:24 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)) 08:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:35:24 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Cz_T8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:35:24 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)) 08:35:24 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)) 08:35:24 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)) 08:35:24 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)) 08:35:24 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)) 08:35:24 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)) 08:35:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:26 INFO - Downloading blessings-1.6.tar.gz 08:35:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:35:26 INFO - Installing collected packages: blessings 08:35:26 INFO - Running setup.py install for blessings 08:35:27 INFO - Successfully installed blessings 08:35:27 INFO - Cleaning up... 08:35:27 INFO - Return code: 0 08:35:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:35:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:27 INFO - Reading from file tmpfile_stdout 08:35:27 INFO - Current package versions: 08:35:27 INFO - blessings == 1.6 08:35:27 INFO - blobuploader == 1.2.4 08:35:27 INFO - docopt == 0.6.1 08:35:27 INFO - manifestparser == 1.1 08:35:27 INFO - mozInstall == 1.12 08:35:27 INFO - mozcrash == 0.17 08:35:27 INFO - mozdebug == 0.1 08:35:27 INFO - mozdevice == 0.48 08:35:27 INFO - mozfile == 1.2 08:35:27 INFO - mozhttpd == 0.7 08:35:27 INFO - mozinfo == 0.9 08:35:27 INFO - mozleak == 0.1 08:35:27 INFO - mozlog == 3.2 08:35:27 INFO - moznetwork == 0.27 08:35:27 INFO - mozprocess == 0.22 08:35:27 INFO - mozprofile == 0.28 08:35:27 INFO - mozrunner == 6.11 08:35:27 INFO - mozscreenshot == 0.1 08:35:27 INFO - mozsystemmonitor == 0.3 08:35:27 INFO - moztest == 0.7 08:35:27 INFO - mozversion == 1.4 08:35:27 INFO - psutil == 3.1.1 08:35:27 INFO - requests == 1.2.3 08:35:27 INFO - wsgiref == 0.1.2 08:35:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:35:27 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')]} 08:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:27 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')]} 08:35:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:27 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 0x10538ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105394570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ad3593ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, '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.IMTiArOwwW/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.ZiKmlITtgd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:35:27 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 08:35:27 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 08:35:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:35:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:27 INFO - 'HOME': '/Users/cltbld', 08:35:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:27 INFO - 'LOGNAME': 'cltbld', 08:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:27 INFO - 'MOZ_NO_REMOTE': '1', 08:35:27 INFO - 'NO_EM_RESTART': '1', 08:35:27 INFO - 'PAGER': '/bin/cat', 08:35:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:27 INFO - 'PWD': '/builds/slave/test', 08:35:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:27 INFO - 'SHELL': '/bin/bash', 08:35:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:35:27 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:35:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:27 INFO - 'USER': 'cltbld', 08:35:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:27 INFO - 'XPC_FLAGS': '0x0', 08:35:27 INFO - 'XPC_SERVICE_NAME': '0', 08:35:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:27 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)) 08:35:27 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)) 08:35:27 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)) 08:35:27 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)) 08:35:27 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)) 08:35:27 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)) 08:35:27 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)) 08:35:27 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)) 08:35:27 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)) 08:35:27 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)) 08:35:27 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)) 08:35:27 INFO - Cleaning up... 08:35:27 INFO - Return code: 0 08:35:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:35:27 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')]} 08:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:27 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')]} 08:35:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:27 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 0x10538ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105394570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ad3593ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, '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.IMTiArOwwW/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.ZiKmlITtgd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:35:27 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 08:35:27 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 08:35:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:35:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:27 INFO - 'HOME': '/Users/cltbld', 08:35:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:27 INFO - 'LOGNAME': 'cltbld', 08:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:27 INFO - 'MOZ_NO_REMOTE': '1', 08:35:27 INFO - 'NO_EM_RESTART': '1', 08:35:27 INFO - 'PAGER': '/bin/cat', 08:35:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:27 INFO - 'PWD': '/builds/slave/test', 08:35:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:27 INFO - 'SHELL': '/bin/bash', 08:35:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:35:27 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:35:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:27 INFO - 'USER': 'cltbld', 08:35:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:27 INFO - 'XPC_FLAGS': '0x0', 08:35:27 INFO - 'XPC_SERVICE_NAME': '0', 08:35:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:27 INFO - Downloading/unpacking jsonschema 08:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:35:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:35:30 INFO - Downloading/unpacking functools32 (from jsonschema) 08:35:30 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 08:35:30 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 08:35:30 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 08:35:30 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 08:35:30 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 08:35:30 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 08:35:30 INFO - Downloading functools32-3.2.3-2.tar.gz 08:35:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:35:30 INFO - warning: no files found matching '*.txt' 08:35:30 INFO - no previously-included directories found matching 'build' 08:35:30 INFO - no previously-included directories found matching 'dist' 08:35:30 INFO - no previously-included directories found matching '.git*' 08:35:30 INFO - Installing collected packages: jsonschema, functools32 08:35:30 INFO - Running setup.py install for functools32 08:35:30 INFO - warning: no files found matching '*.txt' 08:35:30 INFO - no previously-included directories found matching 'build' 08:35:30 INFO - no previously-included directories found matching 'dist' 08:35:30 INFO - no previously-included directories found matching '.git*' 08:35:30 INFO - Successfully installed jsonschema functools32 08:35:30 INFO - Cleaning up... 08:35:31 INFO - Return code: 0 08:35:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:35:31 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')]} 08:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:31 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')]} 08:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:35:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10538ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105394570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ad3593ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, '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.IMTiArOwwW/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.ZiKmlITtgd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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 08:35:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:35:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:35:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:35:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:31 INFO - 'HOME': '/Users/cltbld', 08:35:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:31 INFO - 'LOGNAME': 'cltbld', 08:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:31 INFO - 'MOZ_NO_REMOTE': '1', 08:35:31 INFO - 'NO_EM_RESTART': '1', 08:35:31 INFO - 'PAGER': '/bin/cat', 08:35:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:31 INFO - 'PWD': '/builds/slave/test', 08:35:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:31 INFO - 'SHELL': '/bin/bash', 08:35:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:35:31 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:35:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:31 INFO - 'USER': 'cltbld', 08:35:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:31 INFO - 'XPC_FLAGS': '0x0', 08:35:31 INFO - 'XPC_SERVICE_NAME': '0', 08:35:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:35:31 INFO - Downloading/unpacking flake8 08:35:31 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 08:35:31 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 08:35:31 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 08:35:31 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 08:35:31 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 08:35:31 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 08:35:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:35:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:35:33 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 08:35:33 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 08:35:33 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 08:35:33 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 08:35:33 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 08:35:33 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 08:35:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:35:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:35:34 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 08:35:34 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 08:35:34 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 08:35:34 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 08:35:34 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 08:35:34 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 08:35:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:35:34 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 08:35:34 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 08:35:34 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 08:35:34 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 08:35:34 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 08:35:34 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 08:35:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:35:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:35:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:35:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:35:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:35:35 INFO - no previously-included directories found matching 'docs/_build' 08:35:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:35:35 INFO - Running setup.py install for pep8 08:35:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:35:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:35:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:35:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:35:35 INFO - no previously-included directories found matching 'docs/_build' 08:35:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:35:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:35:35 INFO - Cleaning up... 08:35:35 INFO - Return code: 0 08:35:35 INFO - Running post-action listener: _resource_record_post_action 08:35:35 INFO - Running post-action listener: _start_resource_monitoring 08:35:35 INFO - Starting resource monitoring. 08:35:35 INFO - ##### 08:35:35 INFO - ##### Finished create-virtualenv step (success) 08:35:35 INFO - ##### 08:35:35 INFO - ##### 08:35:35 INFO - ##### Running install step. 08:35:35 INFO - ##### 08:35:35 INFO - Running pre-action listener: _resource_record_pre_action 08:35:35 INFO - Running main action method: install 08:35:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:35:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:35:35 INFO - Reading from file tmpfile_stdout 08:35:35 INFO - Detecting whether we're running mozinstall >=1.0... 08:35:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:35:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:35:35 INFO - Reading from file tmpfile_stdout 08:35:35 INFO - Output received: 08:35:35 INFO - Usage: mozinstall [options] installer 08:35:35 INFO - Options: 08:35:35 INFO - -h, --help show this help message and exit 08:35:35 INFO - -d DEST, --destination=DEST 08:35:35 INFO - Directory to install application into. [default: 08:35:35 INFO - "/builds/slave/test"] 08:35:35 INFO - --app=APP Application being installed. [default: firefox] 08:35:35 INFO - mkdir: /builds/slave/test/build/application 08:35:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:35:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:36:09 INFO - Reading from file tmpfile_stdout 08:36:09 INFO - Output received: 08:36:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:36:09 INFO - Running post-action listener: _resource_record_post_action 08:36:09 INFO - ##### 08:36:09 INFO - ##### Finished install step (success) 08:36:09 INFO - ##### 08:36:09 INFO - ##### 08:36:09 INFO - ##### Running run-tests step. 08:36:09 INFO - ##### 08:36:09 INFO - Running pre-action listener: _resource_record_pre_action 08:36:09 INFO - Running main action method: run_tests 08:36:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:36:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:36:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:36:09 INFO - 2016-06-02 08:36:09.860 screenresolution[1643:7497] starting screenresolution argv=screenresolution get 08:36:09 INFO - 2016-06-02 08:36:09.863 screenresolution[1643:7497] Display 0: 1600x1200x32@60 08:36:09 INFO - 2016-06-02 08:36:09.868 screenresolution[1644:7499] starting screenresolution argv=screenresolution list 08:36:09 INFO - Available Modes on Display 0 08:36:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:36:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:36:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:36:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:36:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:36:10 INFO - Intel Iris: 08:36:10 INFO - Chipset Model: Intel Iris 08:36:10 INFO - Type: GPU 08:36:10 INFO - Bus: Built-In 08:36:10 INFO - VRAM (Dynamic, Max): 1536 MB 08:36:10 INFO - Vendor: Intel (0x8086) 08:36:10 INFO - Device ID: 0x0a2e 08:36:10 INFO - Revision ID: 0x0009 08:36:10 INFO - Displays: 08:36:10 INFO - Display: 08:36:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:36:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:36:10 INFO - Display Serial Number: 1600x1200 60 08:36:10 INFO - Main Display: Yes 08:36:10 INFO - Mirror: Off 08:36:10 INFO - Online: Yes 08:36:10 INFO - Rotation: Supported 08:36:10 INFO - Return code: 0 08:36:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:36:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:36:10 INFO - Python 2.7.3 08:36:10 INFO - Return code: 0 08:36:10 INFO - grabbing minidump binary from tooltool 08:36:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:36:10 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 0x105232d30>, '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 0x105056db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105229b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:36:10 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 08:36:10 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 08:36:10 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 08:36:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:36:10 INFO - Return code: 0 08:36:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:36:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:36:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:36:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:36:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:36:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:36:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:36:11 INFO - Return code: 0 08:36:11 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/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--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 08:36:11 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/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0214 --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 08:36:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMTiArOwwW/Render', 08:36:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:11 INFO - 'HOME': '/Users/cltbld', 08:36:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:11 INFO - 'LOGNAME': 'cltbld', 08:36:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:36:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:36:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:11 INFO - 'MOZ_NO_REMOTE': '1', 08:36:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:36:11 INFO - 'NO_EM_RESTART': '1', 08:36:11 INFO - 'PAGER': '/bin/cat', 08:36:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:11 INFO - 'PWD': '/builds/slave/test', 08:36:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:36:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:11 INFO - 'SHELL': '/bin/bash', 08:36:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZiKmlITtgd/Listeners', 08:36:11 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 08:36:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:11 INFO - 'USER': 'cltbld', 08:36:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:11 INFO - 'XPC_FLAGS': '0x0', 08:36:11 INFO - 'XPC_SERVICE_NAME': '0', 08:36:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:11 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/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--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 08:36:11 INFO - mozversion application_buildid: 20160602074145 08:36:11 INFO - mozversion application_changeset: a47a4d3877eb3a732d7578d77ade1142b1b941e7 08:36:11 INFO - mozversion application_display_name: Nightly 08:36:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:36:11 INFO - mozversion application_name: Firefox 08:36:11 INFO - mozversion application_remotingname: firefox 08:36:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:36:11 INFO - mozversion application_vendor: Mozilla 08:36:11 INFO - mozversion application_version: 49.0a1 08:36:11 INFO - mozversion platform_buildid: 20160602074145 08:36:11 INFO - mozversion platform_changeset: a47a4d3877eb3a732d7578d77ade1142b1b941e7 08:36:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:36:11 INFO - mozversion platform_version: 49.0a1 08:36:11 INFO - using testdate: 1464881771 08:36:11 INFO - actual date: 1464881771 08:36:11 INFO - starting webserver on 'localhost:49277' 08:36:11 INFO - SUITE-START | Running 2 tests 08:36:11 INFO - TEST-START | dromaeo_css 08:36:11 INFO - operating with platform_type : mac_ 08:36:11 INFO - Initialising browser for dromaeo_css test... 08:36:11 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmps1x9tq/profile http://localhost:49277/getInfo.html) 08:36:13 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 08:36:13 INFO - PROCESS | 1662 | colorDepth:24 08:36:13 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 08:36:13 INFO - PROCESS | 1662 | __metrics 08:36:14 INFO - PROCESS | 1662 | 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" 08:36:14 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:36:14 INFO - TEST-INFO | 1662: exit 0 08:36:14 INFO - Browser initialized. 08:36:14 INFO - Running cycle 1/1 for dromaeo_css test... 08:36:14 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmps1x9tq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:36:26 INFO - PROCESS | 1664 | RSS: Main: 242159616 08:36:26 INFO - PROCESS | 1664 | 08:37:17 INFO - PROCESS | 1664 | 1464881837918 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] 08:39:27 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 08:39:29 INFO - PROCESS | 1664 | RSS: Main: 329031680 08:39:29 INFO - PROCESS | 1664 | 08:42:32 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 08:42:32 INFO - PROCESS | 1664 | RSS: Main: 381444096 08:42:32 INFO - PROCESS | 1664 | 08:45:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 08:45:35 INFO - PROCESS | 1664 | RSS: Main: 372867072 08:45:35 INFO - PROCESS | 1664 | 08:48:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 08:48:38 INFO - PROCESS | 1664 | RSS: Main: 375083008 08:48:38 INFO - PROCESS | 1664 | 08:51:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 08:51:42 INFO - PROCESS | 1664 | RSS: Main: 347136000 08:51:42 INFO - PROCESS | 1664 | 08:54:45 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 08:54:45 INFO - PROCESS | 1664 | RSS: Main: 376016896 08:54:45 INFO - PROCESS | 1664 | 08:54:45 INFO - PROCESS | 1664 | __start_tp_report 08:54:45 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 08:54:45 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 08:54:45 INFO - PROCESS | 1664 | |i|pagename|runs| 08:54:45 INFO - PROCESS | 1664 | |0;dojo.html;3416.86332546698;3425.8629654813803;3454.8445319960606;3461.4115600347936;3510.613832478427;5540.612157149001;5607.243022192003;5635.070213414781;5650.830475085743;5674.71626418679;5607.467290607396;5656.038473459512;5731.197632331477;5749.511291540213;5789.363170051291;5092.898142037176;5098.56662183713;5147.948520514784;5162.5095615916425;5244.632875698702;5216.8695782605355;5238.895222095556;5270.525652691257;5274.367075950872;5298.125809241471;5088.160453525164;5088.338515992916;5107.233914912756;5122.641415100908;5142.948570514284;5101.591872650179;5106.387233531963;5109.591232701375;5128.333316668846;5160.7419629018395;5106.208537676666;5137.972405518911;5167.276581278624;5187.273781670569;5188.6367954243215;5498.202760599731;5549.694766787825;5570.693611851347;5616.831495055155;5618.775248990036;6153.7230824613;6167.722452489649;6184.257889053342;6185.721642526097;6191.628502289832;6220.346863579304;6279.183706118221;6283.246010478726;6284.905726414107;6303.716332765037;5589.301337332833;5665.886682266373;5672.8865422691315;5728.026235539968;5775.075987841925;6251.093591429218;6326.145970293951;6332.33510481402;6335.493160547145;6339;5992.34084250732;6006.189164462829;6019.277686677627;6096.960962154926;6098.804430405139;6174.5060395168275;6222.564420490522;6225.564210505221;6235.407636274546;6238.1266622672865;5956.017257152521;6152.16945712332;6162.106494558354;6168.1056246843955;6172.105044768573;6067.180930574405;6130.509559235251;6131.804871722038;6136.556615553651;6183.752649893954;4842.782074806642;4855.830045948445;4870.512948705172;4875.049365373789;4891.192953162759;4344.196323680129;4396.428464299621;4404.075144219686;4425.933610995838;4439;5581.441855814386;5653.038983372754;5659.5755318351285;5664;5673.489385955284;6136.67225816756;6184.969075154595;6193.287771906198;6211.254649442006;6293.118963345135;5651.9152212716845;5658.321001479849;5664.320281566238;5666.065099258659;5706.087026075808;5178.637495375363;5317.096093664087;5321.86695332612;5333.146696528537;5348.438413966555;6246.000639897686;6249.468795152463;6288.434040936338;6299.96850015747;6307.905381419374;6252.406021427957;6254.6247225166635;6265.404786545271;6276.529260305587;6289.182406287154;6222.719977601109;6256.718447669775;6270.216222972129;6283.717232724447;6284.874302514016;6104.11490333908;6153.046277826944;6154.507639388928;6161.784337548164;6163.260408750979;6232.283287422005;6247;6247.781327653511;6253.405926436982;6257.843553911189;4418.2709852875205;5955.136505206808;5960.821175364746;5981.401859813984;6034.39656034393;6033.064874944391;6036.517078633611;6038.849028774316;6039.818805435844;6047.244094488189;5811.331696854747;5872.207252020922;5876.6180198286975;5895.351511333835;5920.970395147997;5857.121431785266;5909.674967876808;5944.554158438048;5976.103584462366;6072.210612620508;5829.621074630136;5829.912551311649;5842.532597392284;5849.795257165979;5854.063349864001 08:54:45 INFO - PROCESS | 1664 | |1;ext.html;513.9306193663856;518.8521271437638;538.7064050092698;543.4918351341496;556.2768401078602;30673;31225.375492490137;31238.53142202866;31273;42162.578374216246;42871.78564107183;45372.86567835806;45407.54592454075;45531.31703024449;45604.77197614015;9439.858402123957;9702.981186975372;9724.124828765409;9726.902730972706;9741.366811157252;7906.52560846351;9527;9709.805803883917;9719.17387022104;9719.902800972006;9493.52532373382;9505;9518.095780900803;9522.1906137978;9536.141747242747;9625.566849491755;9689.999999999965;9697.563609637513;9699.030096990315;9707.951460242653;9479.28905332096;9547.188488978445;9558.761030974281;9566.282528810367;9577.329586928918;41079;41514.1697166058;41545.79227103875;41559;41584;3399.456087026064;3514.5431093957877;3559.288142371536;3617.9933140360363;3973.324534829086;31603.683963160303;32275.83862080698;32568.348633027443;32669;32945.6705432945;3251.6260630027614;3344.9331013379838;3398.8300584970652;3404.097914052778;3411.5906091269208;482779;483437;483984;484294;484995.15004849853;65636.6718166406;65694.01458978119;66082.33917660858;66126;66304.33695663078;69712;69757.65121174362;69794;69878.65060674766;69994.30005699878;17391.56521086983;17626.823731762775;17642.911785440992;17661.61568188278;17663.911680441517;12505.499780008699;13303.733925321632;13320;13323.66690832737;13329.000324975666;18645.906770466332;20080.59838803216;20188;20231.4942126448;20243.493912652302;6955.443564514827;7567.281108294703;8055.073666528424;8094.27151556351;8127.512349259064;941.9011003844635;1033.7002269341806;1047.0785708576404;1074.4949873559572;1123.6516679829278;24920.626190607156;30482.085537433468;31300.68699313023;31370;31856.20359490985;1039.8180318444392;1141.851559297286;1187.352892673495;1210.2012671636853;1215.2647648172729;15934.043957362595;16081.839181608035;16186.514404567883;16255;16271.51185464438;658097;665496;666278;666845;666923.3307666861;59734.70132649309;62093.068603970976;62135.37864621387;62194.0670889937;62201.68899155475;1292.160095937648;1350.6150747037243;1423.8893662942507;1444.6388402899274;1480.6150400895845;30303;31684.5247321295;31737;31773.04680859578;32467.837660811543;52889;53263.733681331345;53301;53380.733096335825;53403.19895201653;508164;527746;528043;528529;528960;24199.15302964388;26237.475250494503;26755.86622066955;27078.05226817052;27483.725162748116;48938;49401.50598494113;49649;50119;50148.99702005866;41338.36643798016;41353.37969930514;41378.379324310765;41453.58546414618;42316;38706.225875482894;39606.60393396144;39683.80158099191;39856.8801868511;40500;38988.80505597454;39680;39827.80516584507;39897.60102399055;40036.59963400329;31855.681443185273;33938.32123357568;34901.47647785336;35418.29163416769;35565.46651800173;20290;22483.32550023502;25172.622410663487;25638.359041023377;26878.865605671846 08:54:45 INFO - PROCESS | 1664 | |2;jquery.html;389.561743039081;391.27028092607276;392.8232295467039;393.6968534228644;417.3385184482595;12977.610671679864;19747.605047899033;20094.296699615516;21215.257465988692;21229.68155477669;14564.417423303055;16369.918150409261;17888.195031223626;19978.60042799143;20205;5596.4123767004385;5847.678377689225;5917.8816423671715;5920.378360272174;5937.792177273796;5755.827325180251;5802.042662960627;5808.506276966463;5873.471387575118;5878;5558.8610284743;5693.857653558653;5731.914021289684;5749.281339832521;5749.971250143764;5665.405132461094;5699.145128230757;5719.714014299289;5781.768729250825;5793.159991801207;4866.172750632366;4982.451930287665;5005.1491246488185;5019.598432125457;5029.698218106925;24620.630690539656;24776.752232477626;25460;25808.483830323476;25837;2472.24596498068;3047.1620304416465;3062.9234269143226;3078.5536097265995;3172.6510083890753;14703.55889323322;15251.161186134757;15369;15460.613484662861;16099.356025759073;1858.4610462965725;1912.512309361104;1971.1721077147633;1971.8521110916595;1972.1026932745567;106844;111283;111898.99999999919;112158;112509.87490125076;8693.826123477495;8710.608022638997;10361.015703508305;10833.78332433347;10979.945100274448;13570.117942333716;13670.043096983116;13706.38321275545;13716.794248086288;13731.794023089662;7156.676014937253;7624.161342252348;8059.677612895419;8191.262786349138;8304.584770761438;8676.522791246416;8682.044975052739;8686.0890604067;8753.824923501621;8820.162084601952;5385.3268341457315;6550.5762867738285;6578.934210657928;6585.703643336062;6588.505862060365;1583.4932821497011;1802.92788288467;1824.087956021989;1855.1188185611995;1868.6823240048952;951;966.8984756600596;986.713852982627;1011.7925825205815;1044.2481413382352;16299.837001630069;20029;21398.03708833106;21471.78528214729;21701;996.9451680157516;1012.4026824173773;1027.72765217216;1053.167997282154;1075.3118004477142;11286.492107855167;11534.942325288488;11661.591844285578;11661.766764664659;11665.00847427978;606396;609305.9534702387;616172;622897;626875;67181;71120.22199444952;73928.63035684788;112706.43646781877;112993;881.8897637795276;1029.264076185531;1045.4197920154236;1060.7931453366673;1063.3991794636006;18843.528911777314;19062.14220360115;19142.52143696363;19189.424317270503;19212.327568535035;48519;50567.49432505628;51482;51760.705982350744;52095;69016.65491672509;72270.63864680643;72562.63718681372;74282.62858685672;80066;6697.866042679022;7150.96424517874;7576.583287919218;7965.402594805297;8134.959325203336;12842.422091006114;12936.870631293566;12961.805572916226;13203.471861125447;13235.602931912059;5461.1808228766;6511.055896894829;11227.607033753779;11242.494087765906;11325.810789867275;9534;10493.842592361274;11074.612388566684;11285.492152852978;11403.372814495282;11401.771964560829;11464.713382165513;11522.769544608893;11663.650090497298;11773.587924422603;6340.270868850142;7286.380657643947;7448.888266676113;7491.9625401872645;7637.847243055219;8339.041010283652;10199.337043092175;11423.257488263236;11541.422928853692;11559.364234967157 08:54:45 INFO - PROCESS | 1664 | |3;mootools.html;1419.9432022719093;1572.1196130167104;1589.7615357696345;1839.3746126317048;1968.3602829080553;7779.844403111942;8265.958670206655;8276.503409795416;8284.171582841713;8547.700830470934;8597;8786.428882122658;8800.339974501905;8800.735977920656;8815.867761983576;2318.3392733071078;2406.302172370011;2407.518496300747;2408.5784987627185;2409.987950060256;2352.9529409411803;2389.2235023617395;2392.569337519246;2394.6408038794234;2397.2927986244013;2352.2120089769946;2358.551875143717;2375.168690958168;2388.3909603051197;2391.330427480308;2342.941426464335;2352.176738141654;2382.0233704181287;2385.2963375804093;2392.3899405651623;2227.8106360959177;2331.6968794056834;2366.503034362786;2366.857988520694;2368.242162507981;8676;8767.342449316327;8768;8773.824523509495;8783.604737786814;5076.340075790124;5202.583793296527;5279.736013199325;5373.75818088183;5379.381371142291;3345.7119009181397;3435.261418794946;3512.367773800715;3670.596234414212;3684.6683798458;1059.9152067834555;1102.3385968418966;1107.34666546737;1108.4457771114444;1125.3754166437625;9853.211743060538;11630.94184529072;11751.64745057641;11772.823407648892;11783.116266279978;4475.328700694857;4493.887652808706;4607.14767767964;4640.234361330343;4652.302154676826;9186.63253469867;9187.219086377732;9204.769880752901;9218.262538996863;9229.154169166193;4409.779511024436;5232.581393488512;5236.476352364809;5237.397699264558;5249.107651699221;4376.518582955872;4535.818567257273;4725.929111063337;4816.54242846929;4912.6561140719805;3778.8110594470168;3786.8674596388996;3866.2654095721723;3885.630865067814;3899.883003509899;1156.9710757231137;1162.587281515067;1167.9357635330139;1186.7863784518697;1191.1126210972707;659.7525927777084;802.2699343597299;952.3143336797494;973.8441849304078;987.6049580167846;5815.88368232633;5886.381929897385;5889.852753681193;6015.398460154037;6087.57386982907;484.82788610043633;485.8299595141743;497.4478329054746;504.50306448148547;630.7224821078803;3827.7511961722957;3943.290207762573;4001.339778936443;4046.5144182698264;4047.5547689754103;10352.844707329396;10756.838647420294;10759.677209683721;10771.407572583426;10801.67594972152;10522.947385263025;10703.83944240837;10711.89288107109;10727.570897164182;10727.78544429107;8121.390895682729;8203.83592328162;8230.300424463869;8231.09457959636;8233.34133269337;5631.155326701028;5754.108113242454;5821.922944255326;5871.442212989759;5947.137665038632;9571;9929.205663547044;10066.647667331608;10081.798364032826;10083.848742269018;10129.64546240878;10190.847137292798;10191.592336306465;10196.18430525571;10198.541065652213;6908.274631163857;6978.546394484342;6982.9301706984315;6984.720611175496;6992.091028166509;7991.84016319682;8179.79550511242;8245.422820402755;8261.256486916207;8268.173182681683;7298.598577078312;7397.556146631219;7403.51877127985;7486.6256687166515;7531.774046778605;7267.127944646465;7288.708451661874;7289.307515785992;7340.853182936418;7410.407167426701;6816.625085620339;6971.9651401744695;6974.616396098263;6978.651067446506;6983.790486285419;4708.128996135726;4839.322494850653;4960.975195124001;5020.573251273682;5105.846824595268;4826.831060912851;4964.429090654621;5035.748212589429;5185.274061631299;5190.2214667800135 08:54:45 INFO - PROCESS | 1664 | |4;prototype.html;277.21824455036796;297.95381715834037;300.19697309696545;307.6784759925877;318.1489515545914;4359.4768627764715;5165.405978312495;5174.404943431507;5227.5556577690895;5234.947650523493;4585.862424127282;4811.206150985084;5065.569426598743;5073.492650734934;5174.586033117351;3470.848074193997;3650.215203731197;3973.721839471238;3974.801259937006;3981.98009009956;3607.492792509207;3698.907527311812;3767.3783825668734;3980.800959951991;3990.521137463494;3813.455550502051;3944.0871177479044;3970;3977.1846771411792;3979.9055259803495;3880.7387599030426;3926;3941.9408708869246;3944.2900277949957;3952.664023558001;3148.3073723780963;3294.8682052717863;3430.451127819537;3442.4492081266876;3444.121748954001;4376.365427013097;4922.532359425849;4981.950180498185;5029.43498815603;5089.491050894918;3736.532933383327;4218.240716670998;4465.508794032654;4541.931871021938;4576.565226303496;5383.981007122329;5437.374701909292;5442.292501974757;5470.9452905470835;5481.780728770845;3244.821534815584;3672.669459748609;3773.490578771858;3800.0689830991582;3835.117922877726;5096.592272618219;5368.463153684639;5614.185943038277;5786.189933409326;5810.1865738796605;3101.4882544379925;3685.852565897334;3823.3882578787816;3839.3665045267217;3874.341361968472;3865.265599536079;3867.3425517662067;3875.159299332851;3889.844406223776;3890.7822541151836;3433.7939723616664;3768;4238.554951730086;4351.412559304454;4437.977810110993;4209.894752631209;5400.675959442446;5413.53984911287;5443.265159203536;5453.972730136324;1290.9548165814151;1314.9539766108333;1317.5191055264952;1320.7716823354372;1321.9471221151045;767.4167632599153;770.6917233106802;809.2029351089172;820.798904268458;825.3685930263354;3869.2068126034096;4192.874213773592;4214.599613036757;4221.409002739619;4233.407322974725;4055.533613634411;4376.693631445832;4682.642033810157;4687.367205427224;4712.5758681718125;3581.6568786704984;3858.6687592780445;4061.7969101544804;4199.853005144805;4209.663226941899;2037.6099069999007;2072.8859912704947;2186.6392045312346;2296.8621882687094;2509.381448965407;4054.3310353791294;4737.431508219036;4865.391826021747;4916.778744956486;4942.751048273027;4076.9592304077173;4216.362001798059;4304.18220538103;4548.999220171557;4552.2488789350045;4237.6821738369745;4516.661250406232;4538.251188553918;4670.439547254351;4900.5742157630775;3961.6038396161307;4265.808038638207;4278.935815962701;4664.976675116603;4696.688834371691;3911.941320880132;4188.476440444944;4366.672499562609;4607.907841843212;4680.648951328732;4738.976305118453;5406.432324605859;5523.475269849358;5661.971690141523;5896.292444906639;3406.284680217182;3661.8169091544783;3712.3108985412114;3785.9399368176955;3815.713821463346;5176.352955880515;5267.710275934707;5324;5647.463490968352;5656.43435656431;5427.158790387496;5440.319960004999;5453.7545810439415;5464.426235245401;5467.2231524753815;4507.932381014221;5284.943011397659;5371.194320851903;5440.455954404528;5459.389480103299;4966.503349665005;5082.390113186441;5298.933415967853;5313.591898147071;5316.760745766373;4398.714083584557;4668.533146685304;5175.680201548581;5399.406065332889;5443.836684899459;4499.707519011254;4866.78099485531;4956.835143741146;5337.492938170868;5369.6105233170865 08:54:45 INFO - PROCESS | 1664 | |5;yui.html;716.3194964783455;758.8330567275201;821.1870248454032;824.6495239523205;850.6002178975883;14325.355359008843;15775.368985240577;15797.842021579781;15814.367425302975;15818.446354377586;14221.928890355559;14397.136171829698;14430.350634221459;14436.278186090705;14459.060161089521;702.7434986230015;729.4638440746047;883.9646414143426;914.0630853375277;922.5525620074259;892.5448021509045;904.4256896870502;908.4867050116696;911.8449863523217;913.159892898535;951.5432592355673;953.5327689432163;953.8521529162956;954.1555723184997;955.2071780422268;728.1953441447204;753.8944547763344;756.9583672897988;757.0083191019781;919.852823548229;715.5027256057042;717.3436305780257;717.3830505765038;717.6053170756063;718.6370882704201;15431.228438578026;15537.378504859793;15553.144577048259;15574.299156537869;15594.220288985618;14249.857501424956;14264.643383915381;14304.856951430456;14417.27913604326;14428.711425771531;13212.603621891358;13363.612022887566;13370.933145334307;13384.130031547918;13767.518136865161;4589.770511474447;4705.882352941169;4728.267118596623;4840.685355451919;4842.177732767263;110093.44953275182;110642;110860.44569777181;110913;111011.88988110096;11160.720981975353;11536;11765.941170294094;11796.410179490991;11853.822192667118;23151.53696926052;23165.3050408488;23177.53644927092;23178.884105579364;23276.06895724186;7540.208278130827;8013.719519816778;8298.087210406851;8313.625886834985;8320.667173312999;4020.4170395292517;4431.357453169272;4560.566746159109;4643.907121857544;4730.432348118179;3274.361499507573;3432.5880894292845;3506.403911335079;3571.2500374921556;3582.355176068281;592.6769910398757;623.5074291309905;650.2002536879664;661.2395744893411;662.8077857421293;631;633.9809805705836;647.3946859686208;657.204782213527;667.9833004174837;8216.753497395088;10291.35338345861;10636.574537018587;10741.301815381976;10764.13886889047;539.2154415325691;571.4285714285714;691.4502970138651;707.702764838769;717.325713828999;4526.298423744325;4599.494055653875;4642.419697537808;4687;4892.315075889378;135769;142288;142397;142933;146920;93340;111651.44174279076;118793;124182.3790881058;127090.72909270973;108092.45953770338;108450;110222;110318.34522482169;111635.32547011964;8301.875471867806;8657.134286571292;8831.205191532792;8865.68970086044;9127.634894604142;58703.4129658715;59002.40997589969;60047.69976150091;60615;60866.39133608784;72571;87881;90074.64888026554;92875;94451.05548944423;12145.210561313486;12192.817107743569;12288.692782680504;12481.56314528987;12718.491260349852;12562.685932851751;12623.305718185587;12766.61700148997;13317.866821331663;13727.313634318045;11994.855074631414;12164.452599632861;12320.260784352968;12324.568640097554;12584.685382865135;12103.273803571814;12233.3271670059;12294.569690060805;12692.746145076862;12717.872821271669;11917.940410297893;12251.87748122543;12394.938025309815;12674.683132921382;12704.6823829405;9874.456904870302;10062.496875156066;10093.394396336244;10324.896751032393;10554.102901253173;9900.653477128266;10343.844842327522;10478.266521343432;10547;11461.942690286494 08:54:45 INFO - PROCESS | 1664 | __end_tp_report 08:54:45 INFO - PROCESS | 1664 | __start_cc_report 08:54:45 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1292 08:54:45 INFO - PROCESS | 1664 | __end_cc_report 08:54:45 INFO - PROCESS | 1664 | __startTimestamp1464882885708__endTimestamp 08:54:45 INFO - PROCESS | 1664 | 08:54:45 INFO - PROCESS | 1664 | ------- Summary: start ------- 08:54:45 INFO - PROCESS | 1664 | Number of tests: 6 08:54:45 INFO - PROCESS | 1664 | 08:54:45 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5690.59 Median:5857.12 stddev:633.67 (10.8%) stddev-sans-first:610.00 08:54:45 INFO - PROCESS | 1664 | Values: 3416.9 3425.9 3454.8 3461.4 3510.6 5540.6 5607.2 5635.1 5650.8 5674.7 5607.5 5656.0 5731.2 5749.5 5789.4 5092.9 5098.6 5147.9 5162.5 5244.6 5216.9 5238.9 5270.5 5274.4 5298.1 5088.2 5088.3 5107.2 5122.6 5142.9 5101.6 5106.4 5109.6 5128.3 5160.7 5106.2 5138.0 5167.3 5187.3 5188.6 5498.2 5549.7 5570.7 5616.8 5618.8 6153.7 6167.7 6184.3 6185.7 6191.6 6220.3 6279.2 6283.2 6284.9 6303.7 5589.3 5665.9 5672.9 5728.0 5775.1 6251.1 6326.1 6332.3 6335.5 6339.0 5992.3 6006.2 6019.3 6097.0 6098.8 6174.5 6222.6 6225.6 6235.4 6238.1 5956.0 6152.2 6162.1 6168.1 6172.1 6067.2 6130.5 6131.8 6136.6 6183.8 4842.8 4855.8 4870.5 4875.0 4891.2 4344.2 4396.4 4404.1 4425.9 4439.0 5581.4 5653.0 5659.6 5664.0 5673.5 6136.7 6185.0 6193.3 6211.3 6293.1 5651.9 5658.3 5664.3 5666.1 5706.1 5178.6 5317.1 5321.9 5333.1 5348.4 6246.0 6249.5 6288.4 6300.0 6307.9 6252.4 6254.6 6265.4 6276.5 6289.2 6222.7 6256.7 6270.2 6283.7 6284.9 6104.1 6153.0 6154.5 6161.8 6163.3 6232.3 6247.0 6247.8 6253.4 6257.8 4418.3 5955.1 5960.8 5981.4 6034.4 6033.1 6036.5 6038.8 6039.8 6047.2 5811.3 5872.2 5876.6 5895.4 5921.0 5857.1 5909.7 5944.6 5976.1 6072.2 5829.6 5829.9 5842.5 5849.8 5854.1 08:54:45 INFO - PROCESS | 1664 | 08:54:45 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:70168.93 Median:41365.88 stddev:150316.83 (363.4%) stddev-sans-first:150647.53 08:54:45 INFO - PROCESS | 1664 | Values: 513.9 518.9 538.7 543.5 556.3 30673.0 31225.4 31238.5 31273.0 42162.6 42871.8 45372.9 45407.5 45531.3 45604.8 9439.9 9703.0 9724.1 9726.9 9741.4 7906.5 9527.0 9709.8 9719.2 9719.9 9493.5 9505.0 9518.1 9522.2 9536.1 9625.6 9690.0 9697.6 9699.0 9708.0 9479.3 9547.2 9558.8 9566.3 9577.3 41079.0 41514.2 41545.8 41559.0 41584.0 3399.5 3514.5 3559.3 3618.0 3973.3 31603.7 32275.8 32568.3 32669.0 32945.7 3251.6 3344.9 3398.8 3404.1 3411.6 482779.0 483437.0 483984.0 484294.0 484995.2 65636.7 65694.0 66082.3 66126.0 66304.3 69712.0 69757.7 69794.0 69878.7 69994.3 17391.6 17626.8 17642.9 17661.6 17663.9 12505.5 13303.7 13320.0 13323.7 13329.0 18645.9 20080.6 20188.0 20231.5 20243.5 6955.4 7567.3 8055.1 8094.3 8127.5 941.9 1033.7 1047.1 1074.5 1123.7 24920.6 30482.1 31300.7 31370.0 31856.2 1039.8 1141.9 1187.4 1210.2 1215.3 15934.0 16081.8 16186.5 16255.0 16271.5 658097.0 665496.0 666278.0 666845.0 666923.3 59734.7 62093.1 62135.4 62194.1 62201.7 1292.2 1350.6 1423.9 1444.6 1480.6 30303.0 31684.5 31737.0 31773.0 32467.8 52889.0 53263.7 53301.0 53380.7 53403.2 508164.0 527746.0 528043.0 528529.0 528960.0 24199.2 26237.5 26755.9 27078.1 27483.7 48938.0 49401.5 49649.0 50119.0 50149.0 41338.4 41353.4 41378.4 41453.6 42316.0 38706.2 39606.6 39683.8 39856.9 40500.0 38988.8 39680.0 39827.8 39897.6 40036.6 31855.7 33938.3 34901.5 35418.3 35565.5 20290.0 22483.3 25172.6 25638.4 26878.9 08:54:45 INFO - PROCESS | 1664 | 08:54:45 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:33953.98 Median:25822.74 stddev:101760.44 (394.1%) stddev-sans-first:102014.70 08:54:45 INFO - PROCESS | 1664 | Values: 389.6 391.3 392.8 393.7 417.3 12977.6 19747.6 20094.3 21215.3 21229.7 14564.4 16369.9 17888.2 19978.6 20205.0 5596.4 5847.7 5917.9 5920.4 5937.8 5755.8 5802.0 5808.5 5873.5 5878.0 5558.9 5693.9 5731.9 5749.3 5750.0 5665.4 5699.1 5719.7 5781.8 5793.2 4866.2 4982.5 5005.1 5019.6 5029.7 24620.6 24776.8 25460.0 25808.5 25837.0 2472.2 3047.2 3062.9 3078.6 3172.7 14703.6 15251.2 15369.0 15460.6 16099.4 1858.5 1912.5 1971.2 1971.9 1972.1 106844.0 111283.0 111899.0 112158.0 112509.9 8693.8 8710.6 10361.0 10833.8 10979.9 13570.1 13670.0 13706.4 13716.8 13731.8 7156.7 7624.2 8059.7 8191.3 8304.6 8676.5 8682.0 8686.1 8753.8 8820.2 5385.3 6550.6 6578.9 6585.7 6588.5 1583.5 1802.9 1824.1 1855.1 1868.7 951.0 966.9 986.7 1011.8 1044.2 16299.8 20029.0 21398.0 21471.8 21701.0 996.9 1012.4 1027.7 1053.2 1075.3 11286.5 11534.9 11661.6 11661.8 11665.0 606396.0 609306.0 616172.0 622897.0 626875.0 67181.0 71120.2 73928.6 112706.4 112993.0 881.9 1029.3 1045.4 1060.8 1063.4 18843.5 19062.1 19142.5 19189.4 19212.3 48519.0 50567.5 51482.0 51760.7 52095.0 69016.7 72270.6 72562.6 74282.6 80066.0 6697.9 7151.0 7576.6 7965.4 8135.0 12842.4 12936.9 12961.8 13203.5 13235.6 5461.2 6511.1 11227.6 11242.5 11325.8 9534.0 10493.8 11074.6 11285.5 11403.4 11401.8 11464.7 11522.8 11663.7 11773.6 6340.3 7286.4 7448.9 7492.0 7637.8 8339.0 10199.3 11423.3 11541.4 11559.4 08:54:45 INFO - PROCESS | 1664 | 08:54:45 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5630.39 Median:485.33 stddev:3161.39 (651.4%) stddev-sans-first:3154.42 08:54:45 INFO - PROCESS | 1664 | Values: 1419.9 1572.1 1589.8 1839.4 1968.4 7779.8 8266.0 8276.5 8284.2 8547.7 8597.0 8786.4 8800.3 8800.7 8815.9 2318.3 2406.3 2407.5 2408.6 2410.0 2353.0 2389.2 2392.6 2394.6 2397.3 2352.2 2358.6 2375.2 2388.4 2391.3 2342.9 2352.2 2382.0 2385.3 2392.4 2227.8 2331.7 2366.5 2366.9 2368.2 8676.0 8767.3 8768.0 8773.8 8783.6 5076.3 5202.6 5279.7 5373.8 5379.4 3345.7 3435.3 3512.4 3670.6 3684.7 1059.9 1102.3 1107.3 1108.4 1125.4 9853.2 11630.9 11751.6 11772.8 11783.1 4475.3 4493.9 4607.1 4640.2 4652.3 9186.6 9187.2 9204.8 9218.3 9229.2 4409.8 5232.6 5236.5 5237.4 5249.1 4376.5 4535.8 4725.9 4816.5 4912.7 3778.8 3786.9 3866.3 3885.6 3899.9 1157.0 1162.6 1167.9 1186.8 1191.1 659.8 802.3 952.3 973.8 987.6 5815.9 5886.4 5889.9 6015.4 6087.6 484.8 485.8 497.4 504.5 630.7 3827.8 3943.3 4001.3 4046.5 4047.6 10352.8 10756.8 10759.7 10771.4 10801.7 10522.9 10703.8 10711.9 10727.6 10727.8 8121.4 8203.8 8230.3 8231.1 8233.3 5631.2 5754.1 5821.9 5871.4 5947.1 9571.0 9929.2 10066.6 10081.8 10083.8 10129.6 10190.8 10191.6 10196.2 10198.5 6908.3 6978.5 6982.9 6984.7 6992.1 7991.8 8179.8 8245.4 8261.3 8268.2 7298.6 7397.6 7403.5 7486.6 7531.8 7267.1 7288.7 7289.3 7340.9 7410.4 6816.6 6972.0 6974.6 6978.7 6983.8 4708.1 4839.3 4961.0 5020.6 5105.8 4826.8 4964.4 5035.7 5185.3 5190.2 08:54:45 INFO - PROCESS | 1664 | 08:54:45 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4151.10 Median:4376.53 stddev:1270.45 (29.0%) stddev-sans-first:1240.30 08:54:45 INFO - PROCESS | 1664 | Values: 277.2 298.0 300.2 307.7 318.1 4359.5 5165.4 5174.4 5227.6 5234.9 4585.9 4811.2 5065.6 5073.5 5174.6 3470.8 3650.2 3973.7 3974.8 3982.0 3607.5 3698.9 3767.4 3980.8 3990.5 3813.5 3944.1 3970.0 3977.2 3979.9 3880.7 3926.0 3941.9 3944.3 3952.7 3148.3 3294.9 3430.5 3442.4 3444.1 4376.4 4922.5 4982.0 5029.4 5089.5 3736.5 4218.2 4465.5 4541.9 4576.6 5384.0 5437.4 5442.3 5470.9 5481.8 3244.8 3672.7 3773.5 3800.1 3835.1 5096.6 5368.5 5614.2 5786.2 5810.2 3101.5 3685.9 3823.4 3839.4 3874.3 3865.3 3867.3 3875.2 3889.8 3890.8 3433.8 3768.0 4238.6 4351.4 4438.0 4209.9 5400.7 5413.5 5443.3 5454.0 1291.0 1315.0 1317.5 1320.8 1321.9 767.4 770.7 809.2 820.8 825.4 3869.2 4192.9 4214.6 4221.4 4233.4 4055.5 4376.7 4682.6 4687.4 4712.6 3581.7 3858.7 4061.8 4199.9 4209.7 2037.6 2072.9 2186.6 2296.9 2509.4 4054.3 4737.4 4865.4 4916.8 4942.8 4077.0 4216.4 4304.2 4549.0 4552.2 4237.7 4516.7 4538.3 4670.4 4900.6 3961.6 4265.8 4278.9 4665.0 4696.7 3911.9 4188.5 4366.7 4607.9 4680.6 4739.0 5406.4 5523.5 5662.0 5896.3 3406.3 3661.8 3712.3 3785.9 3815.7 5176.4 5267.7 5324.0 5647.5 5656.4 5427.2 5440.3 5453.8 5464.4 5467.2 4507.9 5284.9 5371.2 5440.5 5459.4 4966.5 5082.4 5298.9 5313.6 5316.8 4398.7 4668.5 5175.7 5399.4 5443.8 4499.7 4866.8 4956.8 5337.5 5369.6 08:54:45 INFO - PROCESS | 1664 | 08:54:45 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:24093.52 Median:13275.22 stddev:37874.28 (285.3%) stddev-sans-first:37939.86 08:54:45 INFO - PROCESS | 1664 | Values: 716.3 758.8 821.2 824.6 850.6 14325.4 15775.4 15797.8 15814.4 15818.4 14221.9 14397.1 14430.4 14436.3 14459.1 702.7 729.5 884.0 914.1 922.6 892.5 904.4 908.5 911.8 913.2 951.5 953.5 953.9 954.2 955.2 728.2 753.9 757.0 757.0 919.9 715.5 717.3 717.4 717.6 718.6 15431.2 15537.4 15553.1 15574.3 15594.2 14249.9 14264.6 14304.9 14417.3 14428.7 13212.6 13363.6 13370.9 13384.1 13767.5 4589.8 4705.9 4728.3 4840.7 4842.2 110093.4 110642.0 110860.4 110913.0 111011.9 11160.7 11536.0 11765.9 11796.4 11853.8 23151.5 23165.3 23177.5 23178.9 23276.1 7540.2 8013.7 8298.1 8313.6 8320.7 4020.4 4431.4 4560.6 4643.9 4730.4 3274.4 3432.6 3506.4 3571.3 3582.4 592.7 623.5 650.2 661.2 662.8 631.0 634.0 647.4 657.2 668.0 8216.8 10291.4 10636.6 10741.3 10764.1 539.2 571.4 691.5 707.7 717.3 4526.3 4599.5 4642.4 4687.0 4892.3 135769.0 142288.0 142397.0 142933.0 146920.0 93340.0 111651.4 118793.0 124182.4 127090.7 108092.5 108450.0 110222.0 110318.3 111635.3 8301.9 8657.1 8831.2 8865.7 9127.6 58703.4 59002.4 60047.7 60615.0 60866.4 72571.0 87881.0 90074.6 92875.0 94451.1 12145.2 12192.8 12288.7 12481.6 12718.5 12562.7 12623.3 12766.6 13317.9 13727.3 11994.9 12164.5 12320.3 12324.6 12584.7 12103.3 12233.3 12294.6 12692.7 12717.9 11917.9 12251.9 12394.9 12674.7 12704.7 9874.5 10062.5 10093.4 10324.9 10554.1 9900.7 10343.8 10478.3 10547.0 11461.9 08:54:45 INFO - PROCESS | 1664 | -------- Summary: end -------- 08:54:45 INFO - PROCESS | 1664 | 08:54:46 INFO - TEST-INFO | 1664: exit 0 08:54:46 INFO - TEST-OK | dromaeo_css | took 1114764ms 08:54:46 INFO - TEST-START | kraken 08:54:46 INFO - operating with platform_type : mac_ 08:54:46 INFO - Initialising browser for kraken test... 08:54:46 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmp82j0xt/profile http://localhost:49277/getInfo.html) 08:54:48 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 08:54:48 INFO - PROCESS | 1674 | colorDepth:24 08:54:48 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 08:54:48 INFO - PROCESS | 1674 | __metrics 08:54:48 INFO - PROCESS | 1674 | 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" 08:54:48 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:54:49 INFO - TEST-INFO | 1674: exit 0 08:54:49 INFO - Browser initialized. 08:54:49 INFO - Running cycle 1/1 for kraken test... 08:54:49 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmp82j0xt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:55:01 INFO - PROCESS | 1675 | RSS: Main: 242307072 08:55:01 INFO - PROCESS | 1675 | 08:55:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 08:55:04 INFO - PROCESS | 1675 | RSS: Main: 291602432 08:55:04 INFO - PROCESS | 1675 | 08:55:07 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 08:55:08 INFO - PROCESS | 1675 | RSS: Main: 301973504 08:55:08 INFO - PROCESS | 1675 | 08:55:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 08:55:12 INFO - PROCESS | 1675 | RSS: Main: 386195456 08:55:12 INFO - PROCESS | 1675 | 08:55:14 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 08:55:15 INFO - PROCESS | 1675 | RSS: Main: 288460800 08:55:15 INFO - PROCESS | 1675 | 08:55:17 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:55:17 INFO - PROCESS | 1675 | RSS: Main: 293232640 08:55:17 INFO - PROCESS | 1675 | 08:55:20 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 08:55:21 INFO - PROCESS | 1675 | RSS: Main: 328183808 08:55:21 INFO - PROCESS | 1675 | 08:55:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 08:55:24 INFO - PROCESS | 1675 | RSS: Main: 332099584 08:55:24 INFO - PROCESS | 1675 | 08:55:27 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 08:55:27 INFO - PROCESS | 1675 | RSS: Main: 329007104 08:55:27 INFO - PROCESS | 1675 | 08:55:29 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:55:30 INFO - PROCESS | 1675 | RSS: Main: 309698560 08:55:30 INFO - PROCESS | 1675 | 08:55:32 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:55:32 INFO - PROCESS | 1675 | RSS: Main: 316760064 08:55:32 INFO - PROCESS | 1675 | 08:55:34 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:55:35 INFO - PROCESS | 1675 | RSS: Main: 321118208 08:55:35 INFO - PROCESS | 1675 | 08:55:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:55:39 INFO - PROCESS | 1675 | RSS: Main: 324771840 08:55:39 INFO - PROCESS | 1675 | 08:55:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:55:43 INFO - PROCESS | 1675 | RSS: Main: 325578752 08:55:43 INFO - PROCESS | 1675 | 08:55:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 08:55:45 INFO - PROCESS | 1675 | RSS: Main: 369106944 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | __start_tp_report 08:55:45 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 08:55:45 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 08:55:45 INFO - PROCESS | 1675 | |i|pagename|runs| 08:55:45 INFO - PROCESS | 1675 | |0;ai-astar;113;106;114;112;113;112;112;113;113;108 08:55:45 INFO - PROCESS | 1675 | |1;audio-beat-detection;154;120;125;187;142;105;157;141;133;140 08:55:45 INFO - PROCESS | 1675 | |2;audio-dft;183;183;182;183;181;182;182;180;180;183 08:55:45 INFO - PROCESS | 1675 | |3;audio-fft;77;73;57;86;58;57;64;64;80;57 08:55:45 INFO - PROCESS | 1675 | |4;audio-oscillator;83;93;92;70;72;68;94;69;68;104 08:55:45 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;88;84;88;85;87;85;88;85;88;83 08:55:45 INFO - PROCESS | 1675 | |6;imaging-darkroom;93;93;92;97;94;94;94;94;93;94 08:55:45 INFO - PROCESS | 1675 | |7;imaging-desaturate;86;85;87;84;84;85;86;87;85;87 08:55:45 INFO - PROCESS | 1675 | |8;json-parse-financial;56;53;54;67;71;54;54;55;54;55 08:55:45 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;67;73;82;78;74;71;70;71;72;72 08:55:45 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;86;86;83;87;87;86;87;86;125;102 08:55:45 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;144;150;140;139;144;141;148;128;148;144 08:55:45 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;180;229;225;162;170;222;219;172;164;164 08:55:45 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;65;65;67;67;74;82;85;83;83;72 08:55:45 INFO - PROCESS | 1675 | __end_tp_report 08:55:45 INFO - PROCESS | 1675 | __start_cc_report 08:55:45 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2123 08:55:45 INFO - PROCESS | 1675 | __end_cc_report 08:55:45 INFO - PROCESS | 1675 | __startTimestamp1464882945134__endTimestamp 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | ------- Summary: start ------- 08:55:45 INFO - PROCESS | 1675 | Number of tests: 14 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:2.55 (2.3%) stddev-sans-first:2.65 08:55:45 INFO - PROCESS | 1675 | Values: 113.0 106.0 114.0 112.0 113.0 112.0 112.0 113.0 113.0 108.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:141.50 stddev:22.60 (16.0%) stddev-sans-first:23.43 08:55:45 INFO - PROCESS | 1675 | Values: 154.0 120.0 125.0 187.0 142.0 105.0 157.0 141.0 133.0 140.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:181.90 Median:182.50 stddev:1.20 (0.7%) stddev-sans-first:1.20 08:55:45 INFO - PROCESS | 1675 | Values: 183.0 183.0 182.0 183.0 181.0 182.0 182.0 180.0 180.0 183.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:67.30 Median:68.50 stddev:10.87 (15.9%) stddev-sans-first:10.95 08:55:45 INFO - PROCESS | 1675 | Values: 77.0 73.0 57.0 86.0 58.0 57.0 64.0 64.0 80.0 57.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:77.50 stddev:13.54 (17.5%) stddev-sans-first:14.35 08:55:45 INFO - PROCESS | 1675 | Values: 83.0 93.0 92.0 70.0 72.0 68.0 94.0 69.0 68.0 104.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.50 stddev:1.91 (2.2%) stddev-sans-first:1.90 08:55:45 INFO - PROCESS | 1675 | Values: 88.0 84.0 88.0 85.0 87.0 85.0 88.0 85.0 88.0 83.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:1.32 (1.4%) stddev-sans-first:1.36 08:55:45 INFO - PROCESS | 1675 | Values: 93.0 93.0 92.0 97.0 94.0 94.0 94.0 94.0 93.0 94.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.17 (1.4%) stddev-sans-first:1.24 08:55:45 INFO - PROCESS | 1675 | Values: 86.0 85.0 87.0 84.0 84.0 85.0 86.0 87.0 85.0 87.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:55.00 stddev:6.29 (11.4%) stddev-sans-first:6.65 08:55:45 INFO - PROCESS | 1675 | Values: 56.0 53.0 54.0 67.0 71.0 54.0 54.0 55.0 54.0 55.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:72.50 stddev:4.24 (5.9%) stddev-sans-first:3.91 08:55:45 INFO - PROCESS | 1675 | Values: 67.0 73.0 82.0 78.0 74.0 71.0 70.0 71.0 72.0 72.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:86.00 stddev:12.85 (14.9%) stddev-sans-first:13.48 08:55:45 INFO - PROCESS | 1675 | Values: 86.0 86.0 83.0 87.0 87.0 86.0 87.0 86.0 125.0 102.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:144.00 stddev:6.28 (4.4%) stddev-sans-first:6.64 08:55:45 INFO - PROCESS | 1675 | Values: 144.0 150.0 140.0 139.0 144.0 141.0 148.0 128.0 148.0 144.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.70 Median:199.50 stddev:28.99 (14.5%) stddev-sans-first:30.49 08:55:45 INFO - PROCESS | 1675 | Values: 180.0 229.0 225.0 162.0 170.0 222.0 219.0 172.0 164.0 164.0 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:78.00 stddev:8.23 (10.6%) stddev-sans-first:8.02 08:55:45 INFO - PROCESS | 1675 | Values: 65.0 65.0 67.0 67.0 74.0 82.0 85.0 83.0 83.0 72.0 08:55:45 INFO - PROCESS | 1675 | -------- Summary: end -------- 08:55:45 INFO - PROCESS | 1675 | 08:55:45 INFO - TEST-INFO | 1675: exit 0 08:55:45 INFO - TEST-OK | kraken | took 59661ms 08:55:45 INFO - SUITE-END | took 1174s 08:55:46 INFO - Completed test suite (00:19:34) 08:55:46 INFO - javascript benchmark 08:55:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3416.86332546698, 3425.8629654813803, 3454.8445319960606, 3461.4115600347936, 3510.613832478427, 5540.612157149001, 5607.243022192003, 5635.070213414781, 5650.830475085743, 5674.71626418679, 5607.467290607396, 5656.038473459512, 5731.197632331477, 5749.511291540213, 5789.363170051291, 5092.898142037176, 5098.56662183713, 5147.948520514784, 5162.5095615916425, 5244.632875698702, 5216.8695782605355, 5238.895222095556, 5270.525652691257, 5274.367075950872, 5298.125809241471, 5088.160453525164, 5088.338515992916, 5107.233914912756, 5122.641415100908, 5142.948570514284, 5101.591872650179, 5106.387233531963, 5109.591232701375, 5128.333316668846, 5160.7419629018395, 5106.208537676666, 5137.972405518911, 5167.276581278624, 5187.273781670569, 5188.6367954243215, 5498.202760599731, 5549.694766787825, 5570.693611851347, 5616.831495055155, 5618.775248990036, 6153.7230824613, 6167.722452489649, 6184.257889053342, 6185.721642526097, 6191.628502289832, 6220.346863579304, 6279.183706118221, 6283.246010478726, 6284.905726414107, 6303.716332765037, 5589.301337332833, 5665.886682266373, 5672.8865422691315, 5728.026235539968, 5775.075987841925, 6251.093591429218, 6326.145970293951, 6332.33510481402, 6335.493160547145, 6339.0, 5992.34084250732, 6006.189164462829, 6019.277686677627, 6096.960962154926, 6098.804430405139, 6174.5060395168275, 6222.564420490522, 6225.564210505221, 6235.407636274546, 6238.1266622672865, 5956.017257152521, 6152.16945712332, 6162.106494558354, 6168.1056246843955, 6172.105044768573, 6067.180930574405, 6130.509559235251, 6131.804871722038, 6136.556615553651, 6183.752649893954, 4842.782074806642, 4855.830045948445, 4870.512948705172, 4875.049365373789, 4891.192953162759, 4344.196323680129, 4396.428464299621, 4404.075144219686, 4425.933610995838, 4439.0, 5581.441855814386, 5653.038983372754, 5659.5755318351285, 5664.0, 5673.489385955284, 6136.67225816756, 6184.969075154595, 6193.287771906198, 6211.254649442006, 6293.118963345135, 5651.9152212716845, 5658.321001479849, 5664.320281566238, 5666.065099258659, 5706.087026075808, 5178.637495375363, 5317.096093664087, 5321.86695332612, 5333.146696528537, 5348.438413966555, 6246.000639897686, 6249.468795152463, 6288.434040936338, 6299.96850015747, 6307.905381419374, 6252.406021427957, 6254.6247225166635, 6265.404786545271, 6276.529260305587, 6289.182406287154, 6222.719977601109, 6256.718447669775, 6270.216222972129, 6283.717232724447, 6284.874302514016, 6104.11490333908, 6153.046277826944, 6154.507639388928, 6161.784337548164, 6163.260408750979, 6232.283287422005, 6247.0, 6247.781327653511, 6253.405926436982, 6257.843553911189, 4418.2709852875205, 5955.136505206808, 5960.821175364746, 5981.401859813984, 6034.39656034393, 6033.064874944391, 6036.517078633611, 6038.849028774316, 6039.818805435844, 6047.244094488189, 5811.331696854747, 5872.207252020922, 5876.6180198286975, 5895.351511333835, 5920.970395147997, 5857.121431785266, 5909.674967876808, 5944.554158438048, 5976.103584462366, 6072.210612620508, 5829.621074630136, 5829.912551311649, 5842.532597392284, 5849.795257165979, 5854.063349864001], "value": 5651.133590582478, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.9306193663856, 518.8521271437638, 538.7064050092698, 543.4918351341496, 556.2768401078602, 30673.0, 31225.375492490137, 31238.53142202866, 31273.0, 42162.578374216246, 42871.78564107183, 45372.86567835806, 45407.54592454075, 45531.31703024449, 45604.77197614015, 9439.858402123957, 9702.981186975372, 9724.124828765409, 9726.902730972706, 9741.366811157252, 7906.52560846351, 9527.0, 9709.805803883917, 9719.17387022104, 9719.902800972006, 9493.52532373382, 9505.0, 9518.095780900803, 9522.1906137978, 9536.141747242747, 9625.566849491755, 9689.999999999965, 9697.563609637513, 9699.030096990315, 9707.951460242653, 9479.28905332096, 9547.188488978445, 9558.761030974281, 9566.282528810367, 9577.329586928918, 41079.0, 41514.1697166058, 41545.79227103875, 41559.0, 41584.0, 3399.456087026064, 3514.5431093957877, 3559.288142371536, 3617.9933140360363, 3973.324534829086, 31603.683963160303, 32275.83862080698, 32568.348633027443, 32669.0, 32945.6705432945, 3251.6260630027614, 3344.9331013379838, 3398.8300584970652, 3404.097914052778, 3411.5906091269208, 482779.0, 483437.0, 483984.0, 484294.0, 484995.15004849853, 65636.6718166406, 65694.01458978119, 66082.33917660858, 66126.0, 66304.33695663078, 69712.0, 69757.65121174362, 69794.0, 69878.65060674766, 69994.30005699878, 17391.56521086983, 17626.823731762775, 17642.911785440992, 17661.61568188278, 17663.911680441517, 12505.499780008699, 13303.733925321632, 13320.0, 13323.66690832737, 13329.000324975666, 18645.906770466332, 20080.59838803216, 20188.0, 20231.4942126448, 20243.493912652302, 6955.443564514827, 7567.281108294703, 8055.073666528424, 8094.27151556351, 8127.512349259064, 941.9011003844635, 1033.7002269341806, 1047.0785708576404, 1074.4949873559572, 1123.6516679829278, 24920.626190607156, 30482.085537433468, 31300.68699313023, 31370.0, 31856.20359490985, 1039.8180318444392, 1141.851559297286, 1187.352892673495, 1210.2012671636853, 1215.2647648172729, 15934.043957362595, 16081.839181608035, 16186.514404567883, 16255.0, 16271.51185464438, 658097.0, 665496.0, 666278.0, 666845.0, 666923.3307666861, 59734.70132649309, 62093.068603970976, 62135.37864621387, 62194.0670889937, 62201.68899155475, 1292.160095937648, 1350.6150747037243, 1423.8893662942507, 1444.6388402899274, 1480.6150400895845, 30303.0, 31684.5247321295, 31737.0, 31773.04680859578, 32467.837660811543, 52889.0, 53263.733681331345, 53301.0, 53380.733096335825, 53403.19895201653, 508164.0, 527746.0, 528043.0, 528529.0, 528960.0, 24199.15302964388, 26237.475250494503, 26755.86622066955, 27078.05226817052, 27483.725162748116, 48938.0, 49401.50598494113, 49649.0, 50119.0, 50148.99702005866, 41338.36643798016, 41353.37969930514, 41378.379324310765, 41453.58546414618, 42316.0, 38706.225875482894, 39606.60393396144, 39683.80158099191, 39856.8801868511, 40500.0, 38988.80505597454, 39680.0, 39827.80516584507, 39897.60102399055, 40036.59963400329, 31855.681443185273, 33938.32123357568, 34901.47647785336, 35418.29163416769, 35565.46651800173, 20290.0, 22483.32550023502, 25172.622410663487, 25638.359041023377, 26878.865605671846], "value": 20521.513694105048, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.561743039081, 391.27028092607276, 392.8232295467039, 393.6968534228644, 417.3385184482595, 12977.610671679864, 19747.605047899033, 20094.296699615516, 21215.257465988692, 21229.68155477669, 14564.417423303055, 16369.918150409261, 17888.195031223626, 19978.60042799143, 20205.0, 5596.4123767004385, 5847.678377689225, 5917.8816423671715, 5920.378360272174, 5937.792177273796, 5755.827325180251, 5802.042662960627, 5808.506276966463, 5873.471387575118, 5878.0, 5558.8610284743, 5693.857653558653, 5731.914021289684, 5749.281339832521, 5749.971250143764, 5665.405132461094, 5699.145128230757, 5719.714014299289, 5781.768729250825, 5793.159991801207, 4866.172750632366, 4982.451930287665, 5005.1491246488185, 5019.598432125457, 5029.698218106925, 24620.630690539656, 24776.752232477626, 25460.0, 25808.483830323476, 25837.0, 2472.24596498068, 3047.1620304416465, 3062.9234269143226, 3078.5536097265995, 3172.6510083890753, 14703.55889323322, 15251.161186134757, 15369.0, 15460.613484662861, 16099.356025759073, 1858.4610462965725, 1912.512309361104, 1971.1721077147633, 1971.8521110916595, 1972.1026932745567, 106844.0, 111283.0, 111898.99999999919, 112158.0, 112509.87490125076, 8693.826123477495, 8710.608022638997, 10361.015703508305, 10833.78332433347, 10979.945100274448, 13570.117942333716, 13670.043096983116, 13706.38321275545, 13716.794248086288, 13731.794023089662, 7156.676014937253, 7624.161342252348, 8059.677612895419, 8191.262786349138, 8304.584770761438, 8676.522791246416, 8682.044975052739, 8686.0890604067, 8753.824923501621, 8820.162084601952, 5385.3268341457315, 6550.5762867738285, 6578.934210657928, 6585.703643336062, 6588.505862060365, 1583.4932821497011, 1802.92788288467, 1824.087956021989, 1855.1188185611995, 1868.6823240048952, 951.0, 966.8984756600596, 986.713852982627, 1011.7925825205815, 1044.2481413382352, 16299.837001630069, 20029.0, 21398.03708833106, 21471.78528214729, 21701.0, 996.9451680157516, 1012.4026824173773, 1027.72765217216, 1053.167997282154, 1075.3118004477142, 11286.492107855167, 11534.942325288488, 11661.591844285578, 11661.766764664659, 11665.00847427978, 606396.0, 609305.9534702387, 616172.0, 622897.0, 626875.0, 67181.0, 71120.22199444952, 73928.63035684788, 112706.43646781877, 112993.0, 881.8897637795276, 1029.264076185531, 1045.4197920154236, 1060.7931453366673, 1063.3991794636006, 18843.528911777314, 19062.14220360115, 19142.52143696363, 19189.424317270503, 19212.327568535035, 48519.0, 50567.49432505628, 51482.0, 51760.705982350744, 52095.0, 69016.65491672509, 72270.63864680643, 72562.63718681372, 74282.62858685672, 80066.0, 6697.866042679022, 7150.96424517874, 7576.583287919218, 7965.402594805297, 8134.959325203336, 12842.422091006114, 12936.870631293566, 12961.805572916226, 13203.471861125447, 13235.602931912059, 5461.1808228766, 6511.055896894829, 11227.607033753779, 11242.494087765906, 11325.810789867275, 9534.0, 10493.842592361274, 11074.612388566684, 11285.492152852978, 11403.372814495282, 11401.771964560829, 11464.713382165513, 11522.769544608893, 11663.650090497298, 11773.587924422603, 6340.270868850142, 7286.380657643947, 7448.888266676113, 7491.9625401872645, 7637.847243055219, 8339.041010283652, 10199.337043092175, 11423.257488263236, 11541.422928853692, 11559.364234967157], "value": 9499.488936426082, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.9432022719093, 1572.1196130167104, 1589.7615357696345, 1839.3746126317048, 1968.3602829080553, 7779.844403111942, 8265.958670206655, 8276.503409795416, 8284.171582841713, 8547.700830470934, 8597.0, 8786.428882122658, 8800.339974501905, 8800.735977920656, 8815.867761983576, 2318.3392733071078, 2406.302172370011, 2407.518496300747, 2408.5784987627185, 2409.987950060256, 2352.9529409411803, 2389.2235023617395, 2392.569337519246, 2394.6408038794234, 2397.2927986244013, 2352.2120089769946, 2358.551875143717, 2375.168690958168, 2388.3909603051197, 2391.330427480308, 2342.941426464335, 2352.176738141654, 2382.0233704181287, 2385.2963375804093, 2392.3899405651623, 2227.8106360959177, 2331.6968794056834, 2366.503034362786, 2366.857988520694, 2368.242162507981, 8676.0, 8767.342449316327, 8768.0, 8773.824523509495, 8783.604737786814, 5076.340075790124, 5202.583793296527, 5279.736013199325, 5373.75818088183, 5379.381371142291, 3345.7119009181397, 3435.261418794946, 3512.367773800715, 3670.596234414212, 3684.6683798458, 1059.9152067834555, 1102.3385968418966, 1107.34666546737, 1108.4457771114444, 1125.3754166437625, 9853.211743060538, 11630.94184529072, 11751.64745057641, 11772.823407648892, 11783.116266279978, 4475.328700694857, 4493.887652808706, 4607.14767767964, 4640.234361330343, 4652.302154676826, 9186.63253469867, 9187.219086377732, 9204.769880752901, 9218.262538996863, 9229.154169166193, 4409.779511024436, 5232.581393488512, 5236.476352364809, 5237.397699264558, 5249.107651699221, 4376.518582955872, 4535.818567257273, 4725.929111063337, 4816.54242846929, 4912.6561140719805, 3778.8110594470168, 3786.8674596388996, 3866.2654095721723, 3885.630865067814, 3899.883003509899, 1156.9710757231137, 1162.587281515067, 1167.9357635330139, 1186.7863784518697, 1191.1126210972707, 659.7525927777084, 802.2699343597299, 952.3143336797494, 973.8441849304078, 987.6049580167846, 5815.88368232633, 5886.381929897385, 5889.852753681193, 6015.398460154037, 6087.57386982907, 484.82788610043633, 485.8299595141743, 497.4478329054746, 504.50306448148547, 630.7224821078803, 3827.7511961722957, 3943.290207762573, 4001.339778936443, 4046.5144182698264, 4047.5547689754103, 10352.844707329396, 10756.838647420294, 10759.677209683721, 10771.407572583426, 10801.67594972152, 10522.947385263025, 10703.83944240837, 10711.89288107109, 10727.570897164182, 10727.78544429107, 8121.390895682729, 8203.83592328162, 8230.300424463869, 8231.09457959636, 8233.34133269337, 5631.155326701028, 5754.108113242454, 5821.922944255326, 5871.442212989759, 5947.137665038632, 9571.0, 9929.205663547044, 10066.647667331608, 10081.798364032826, 10083.848742269018, 10129.64546240878, 10190.847137292798, 10191.592336306465, 10196.18430525571, 10198.541065652213, 6908.274631163857, 6978.546394484342, 6982.9301706984315, 6984.720611175496, 6992.091028166509, 7991.84016319682, 8179.79550511242, 8245.422820402755, 8261.256486916207, 8268.173182681683, 7298.598577078312, 7397.556146631219, 7403.51877127985, 7486.6256687166515, 7531.774046778605, 7267.127944646465, 7288.708451661874, 7289.307515785992, 7340.853182936418, 7410.407167426701, 6816.625085620339, 6971.9651401744695, 6974.616396098263, 6978.651067446506, 6983.790486285419, 4708.128996135726, 4839.322494850653, 4960.975195124001, 5020.573251273682, 5105.846824595268, 4826.831060912851, 4964.429090654621, 5035.748212589429, 5185.274061631299, 5190.2214667800135], "value": 4472.596029411028, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.21824455036796, 297.95381715834037, 300.19697309696545, 307.6784759925877, 318.1489515545914, 4359.4768627764715, 5165.405978312495, 5174.404943431507, 5227.5556577690895, 5234.947650523493, 4585.862424127282, 4811.206150985084, 5065.569426598743, 5073.492650734934, 5174.586033117351, 3470.848074193997, 3650.215203731197, 3973.721839471238, 3974.801259937006, 3981.98009009956, 3607.492792509207, 3698.907527311812, 3767.3783825668734, 3980.800959951991, 3990.521137463494, 3813.455550502051, 3944.0871177479044, 3970.0, 3977.1846771411792, 3979.9055259803495, 3880.7387599030426, 3926.0, 3941.9408708869246, 3944.2900277949957, 3952.664023558001, 3148.3073723780963, 3294.8682052717863, 3430.451127819537, 3442.4492081266876, 3444.121748954001, 4376.365427013097, 4922.532359425849, 4981.950180498185, 5029.43498815603, 5089.491050894918, 3736.532933383327, 4218.240716670998, 4465.508794032654, 4541.931871021938, 4576.565226303496, 5383.981007122329, 5437.374701909292, 5442.292501974757, 5470.9452905470835, 5481.780728770845, 3244.821534815584, 3672.669459748609, 3773.490578771858, 3800.0689830991582, 3835.117922877726, 5096.592272618219, 5368.463153684639, 5614.185943038277, 5786.189933409326, 5810.1865738796605, 3101.4882544379925, 3685.852565897334, 3823.3882578787816, 3839.3665045267217, 3874.341361968472, 3865.265599536079, 3867.3425517662067, 3875.159299332851, 3889.844406223776, 3890.7822541151836, 3433.7939723616664, 3768.0, 4238.554951730086, 4351.412559304454, 4437.977810110993, 4209.894752631209, 5400.675959442446, 5413.53984911287, 5443.265159203536, 5453.972730136324, 1290.9548165814151, 1314.9539766108333, 1317.5191055264952, 1320.7716823354372, 1321.9471221151045, 767.4167632599153, 770.6917233106802, 809.2029351089172, 820.798904268458, 825.3685930263354, 3869.2068126034096, 4192.874213773592, 4214.599613036757, 4221.409002739619, 4233.407322974725, 4055.533613634411, 4376.693631445832, 4682.642033810157, 4687.367205427224, 4712.5758681718125, 3581.6568786704984, 3858.6687592780445, 4061.7969101544804, 4199.853005144805, 4209.663226941899, 2037.6099069999007, 2072.8859912704947, 2186.6392045312346, 2296.8621882687094, 2509.381448965407, 4054.3310353791294, 4737.431508219036, 4865.391826021747, 4916.778744956486, 4942.751048273027, 4076.9592304077173, 4216.362001798059, 4304.18220538103, 4548.999220171557, 4552.2488789350045, 4237.6821738369745, 4516.661250406232, 4538.251188553918, 4670.439547254351, 4900.5742157630775, 3961.6038396161307, 4265.808038638207, 4278.935815962701, 4664.976675116603, 4696.688834371691, 3911.941320880132, 4188.476440444944, 4366.672499562609, 4607.907841843212, 4680.648951328732, 4738.976305118453, 5406.432324605859, 5523.475269849358, 5661.971690141523, 5896.292444906639, 3406.284680217182, 3661.8169091544783, 3712.3108985412114, 3785.9399368176955, 3815.713821463346, 5176.352955880515, 5267.710275934707, 5324.0, 5647.463490968352, 5656.43435656431, 5427.158790387496, 5440.319960004999, 5453.7545810439415, 5464.426235245401, 5467.2231524753815, 4507.932381014221, 5284.943011397659, 5371.194320851903, 5440.455954404528, 5459.389480103299, 4966.503349665005, 5082.390113186441, 5298.933415967853, 5313.591898147071, 5316.760745766373, 4398.714083584557, 4668.533146685304, 5175.680201548581, 5399.406065332889, 5443.836684899459, 4499.707519011254, 4866.78099485531, 4956.835143741146, 5337.492938170868, 5369.6105233170865], "value": 3754.3813623395645, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.3194964783455, 758.8330567275201, 821.1870248454032, 824.6495239523205, 850.6002178975883, 14325.355359008843, 15775.368985240577, 15797.842021579781, 15814.367425302975, 15818.446354377586, 14221.928890355559, 14397.136171829698, 14430.350634221459, 14436.278186090705, 14459.060161089521, 702.7434986230015, 729.4638440746047, 883.9646414143426, 914.0630853375277, 922.5525620074259, 892.5448021509045, 904.4256896870502, 908.4867050116696, 911.8449863523217, 913.159892898535, 951.5432592355673, 953.5327689432163, 953.8521529162956, 954.1555723184997, 955.2071780422268, 728.1953441447204, 753.8944547763344, 756.9583672897988, 757.0083191019781, 919.852823548229, 715.5027256057042, 717.3436305780257, 717.3830505765038, 717.6053170756063, 718.6370882704201, 15431.228438578026, 15537.378504859793, 15553.144577048259, 15574.299156537869, 15594.220288985618, 14249.857501424956, 14264.643383915381, 14304.856951430456, 14417.27913604326, 14428.711425771531, 13212.603621891358, 13363.612022887566, 13370.933145334307, 13384.130031547918, 13767.518136865161, 4589.770511474447, 4705.882352941169, 4728.267118596623, 4840.685355451919, 4842.177732767263, 110093.44953275182, 110642.0, 110860.44569777181, 110913.0, 111011.88988110096, 11160.720981975353, 11536.0, 11765.941170294094, 11796.410179490991, 11853.822192667118, 23151.53696926052, 23165.3050408488, 23177.53644927092, 23178.884105579364, 23276.06895724186, 7540.208278130827, 8013.719519816778, 8298.087210406851, 8313.625886834985, 8320.667173312999, 4020.4170395292517, 4431.357453169272, 4560.566746159109, 4643.907121857544, 4730.432348118179, 3274.361499507573, 3432.5880894292845, 3506.403911335079, 3571.2500374921556, 3582.355176068281, 592.6769910398757, 623.5074291309905, 650.2002536879664, 661.2395744893411, 662.8077857421293, 631.0, 633.9809805705836, 647.3946859686208, 657.204782213527, 667.9833004174837, 8216.753497395088, 10291.35338345861, 10636.574537018587, 10741.301815381976, 10764.13886889047, 539.2154415325691, 571.4285714285714, 691.4502970138651, 707.702764838769, 717.325713828999, 4526.298423744325, 4599.494055653875, 4642.419697537808, 4687.0, 4892.315075889378, 135769.0, 142288.0, 142397.0, 142933.0, 146920.0, 93340.0, 111651.44174279076, 118793.0, 124182.3790881058, 127090.72909270973, 108092.45953770338, 108450.0, 110222.0, 110318.34522482169, 111635.32547011964, 8301.875471867806, 8657.134286571292, 8831.205191532792, 8865.68970086044, 9127.634894604142, 58703.4129658715, 59002.40997589969, 60047.69976150091, 60615.0, 60866.39133608784, 72571.0, 87881.0, 90074.64888026554, 92875.0, 94451.05548944423, 12145.210561313486, 12192.817107743569, 12288.692782680504, 12481.56314528987, 12718.491260349852, 12562.685932851751, 12623.305718185587, 12766.61700148997, 13317.866821331663, 13727.313634318045, 11994.855074631414, 12164.452599632861, 12320.260784352968, 12324.568640097554, 12584.685382865135, 12103.273803571814, 12233.3271670059, 12294.569690060805, 12692.746145076862, 12717.872821271669, 11917.940410297893, 12251.87748122543, 12394.938025309815, 12674.683132921382, 12704.6823829405, 9874.456904870302, 10062.496875156066, 10093.394396336244, 10324.896751032393, 10554.102901253173, 9900.653477128266, 10343.844842327522, 10478.266521343432, 10547.0, 11461.942690286494], "value": 7799.113685423072, "unit": "score"}], "name": "dromaeo_css", "value": 7242.249503922561, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 114.0, 112.0, 113.0, 112.0, 112.0, 113.0, 113.0, 108.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 125.0, 187.0, 142.0, 105.0, 157.0, 141.0, 133.0, 140.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 182.0, 183.0, 181.0, 182.0, 182.0, 180.0, 180.0, 183.0], "value": 181.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 57.0, 86.0, 58.0, 57.0, 64.0, 64.0, 80.0, 57.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 93.0, 92.0, 70.0, 72.0, 68.0, 94.0, 69.0, 68.0, 104.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 85.0, 87.0, 85.0, 88.0, 85.0, 88.0, 83.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 97.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 84.0, 84.0, 85.0, 86.0, 87.0, 85.0, 87.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 67.0, 71.0, 54.0, 54.0, 55.0, 54.0, 55.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 82.0, 78.0, 74.0, 71.0, 70.0, 71.0, 72.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 83.0, 87.0, 87.0, 86.0, 87.0, 86.0, 125.0, 102.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 150.0, 140.0, 139.0, 144.0, 141.0, 148.0, 128.0, 148.0, 144.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 229.0, 225.0, 162.0, 170.0, 222.0, 219.0, 172.0, 164.0, 164.0], "value": 190.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 67.0, 74.0, 82.0, 85.0, 83.0, 83.0, 72.0], "value": 74.3, "unit": "score"}], "name": "kraken", "value": 1477.3999999999999, "alertThreshold": 2.0}]} 08:55:46 INFO - Return code: 0 08:55:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:55:46 ERROR - # TBPL SUCCESS # 08:55:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:55:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:55:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:55:46 INFO - 2016-06-02 08:55:46.435 screenresolution[1679:9719] starting screenresolution argv=screenresolution get 08:55:46 INFO - 2016-06-02 08:55:46.438 screenresolution[1679:9719] Display 0: 1600x1200x32@60 08:55:46 INFO - 2016-06-02 08:55:46.443 screenresolution[1680:9722] starting screenresolution argv=screenresolution list 08:55:46 INFO - Available Modes on Display 0 08:55:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:55:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:55:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:55:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:55:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:55:46 INFO - Intel Iris: 08:55:46 INFO - Chipset Model: Intel Iris 08:55:46 INFO - Type: GPU 08:55:46 INFO - Bus: Built-In 08:55:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:55:46 INFO - Vendor: Intel (0x8086) 08:55:46 INFO - Device ID: 0x0a2e 08:55:46 INFO - Revision ID: 0x0009 08:55:46 INFO - Displays: 08:55:46 INFO - Display: 08:55:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:55:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:55:46 INFO - Display Serial Number: 1600x1200 60 08:55:46 INFO - Main Display: Yes 08:55:46 INFO - Mirror: Off 08:55:46 INFO - Online: Yes 08:55:46 INFO - Rotation: Supported 08:55:46 INFO - Return code: 0 08:55:46 INFO - Running post-action listener: _resource_record_post_action 08:55:46 INFO - ##### 08:55:46 INFO - ##### Finished run-tests step (success) 08:55:46 INFO - ##### 08:55:46 INFO - Running post-run listener: _resource_record_post_run 08:55:46 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 36918784; Write bytes: 698015232; Read time: 237; Write time: 1897 08:55:46 INFO - TinderboxPrint: CPU usage
53.8% 08:55:46 INFO - TinderboxPrint: I/O read bytes / time
36,918,784 / 237 08:55:46 INFO - TinderboxPrint: I/O write bytes / time
698,015,232 / 1,897 08:55:46 INFO - TinderboxPrint: CPU idle
2,234.9 (46.2%) 08:55:46 INFO - TinderboxPrint: CPU system
964.9 (19.9%) 08:55:46 INFO - TinderboxPrint: CPU user
1,641.8 (33.9%) 08:55:46 INFO - TinderboxPrint: Swap in / out
436,629,504 / 0 08:55:46 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 393650688; Write bytes: 387577344; Read time: 30111; Write time: 766 08:55:46 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35104256; Write bytes: 294963200; Read time: 221; Write time: 1091 08:55:47 INFO - Running post-run listener: _upload_blobber_files 08:55:47 INFO - Blob upload gear active. 08:55:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:55:47 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 08:55:47 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'] 08:55:47 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 08:55:47 INFO - (blobuploader) - INFO - Open directory for files ... 08:55:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:55:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:55:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:48 INFO - (blobuploader) - INFO - Done attempting. 08:55:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:55:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:55:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:49 INFO - (blobuploader) - INFO - Done attempting. 08:55:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:55:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:55:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:55:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:55:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:55:51 INFO - (blobuploader) - INFO - Done attempting. 08:55:51 INFO - (blobuploader) - INFO - Iteration through files over. 08:55:51 INFO - Return code: 0 08:55:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:55:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:55:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a21cb07ca085522cabeaec4a468d1bab3ec24c3fcffd3c415588e1a43f89fe25f92d33e73cd5795cb67646797f33279531309b8fb99dce653a146297200b5af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4aa72081c89fafa5a8ac74e863efe9a4b77e16de5b3da185c00b4e4324278378ea13f5847341ca4e7e20752733e8d9b8c22399d1d025fc2af73ed9f64bd362f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0db7baf64c9702a991ae0a6ec3873e646c2fc2d7cbb1b13732f15a137e02af51a579ac290ceb6ba337f677973fb45d39aa250787904226c46d827c4ab449b43c"} 08:55:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:55:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:55:51 INFO - Contents: 08:55:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a21cb07ca085522cabeaec4a468d1bab3ec24c3fcffd3c415588e1a43f89fe25f92d33e73cd5795cb67646797f33279531309b8fb99dce653a146297200b5af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4aa72081c89fafa5a8ac74e863efe9a4b77e16de5b3da185c00b4e4324278378ea13f5847341ca4e7e20752733e8d9b8c22399d1d025fc2af73ed9f64bd362f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0db7baf64c9702a991ae0a6ec3873e646c2fc2d7cbb1b13732f15a137e02af51a579ac290ceb6ba337f677973fb45d39aa250787904226c46d827c4ab449b43c"} 08:55:51 INFO - Running post-run listener: copy_logs_to_upload_dir 08:55:51 INFO - Copying logs to upload dir... 08:55:51 INFO - mkdir: /builds/slave/test/build/upload/logs 08:55:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.086571 ========= master_lag: 1.49 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-06-02 08:55:53.440953) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 08:55:53.444474) ========= 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.IMTiArOwwW/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.ZiKmlITtgd/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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/6a21cb07ca085522cabeaec4a468d1bab3ec24c3fcffd3c415588e1a43f89fe25f92d33e73cd5795cb67646797f33279531309b8fb99dce653a146297200b5af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4aa72081c89fafa5a8ac74e863efe9a4b77e16de5b3da185c00b4e4324278378ea13f5847341ca4e7e20752733e8d9b8c22399d1d025fc2af73ed9f64bd362f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0db7baf64c9702a991ae0a6ec3873e646c2fc2d7cbb1b13732f15a137e02af51a579ac290ceb6ba337f677973fb45d39aa250787904226c46d827c4ab449b43c"} build_url:https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011032 build_url: 'https://queue.taskcluster.net/v1/task/FOdt4J_LQGW-9EgFojpklQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a21cb07ca085522cabeaec4a468d1bab3ec24c3fcffd3c415588e1a43f89fe25f92d33e73cd5795cb67646797f33279531309b8fb99dce653a146297200b5af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b4aa72081c89fafa5a8ac74e863efe9a4b77e16de5b3da185c00b4e4324278378ea13f5847341ca4e7e20752733e8d9b8c22399d1d025fc2af73ed9f64bd362f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0db7baf64c9702a991ae0a6ec3873e646c2fc2d7cbb1b13732f15a137e02af51a579ac290ceb6ba337f677973fb45d39aa250787904226c46d827c4ab449b43c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 08:55:53.480681) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:55:53.481007) ========= 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.IMTiArOwwW/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.ZiKmlITtgd/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.004862 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:55:53.531856) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 08:55:53.532204) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 08:55:53.532589) ========= ========= Total master_lag: 1.63 =========