builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0307 starttime: 1463128482.06 results: success (0) buildid: 20160512224705 builduid: 176baf6029694ac687ff2a990a6d4375 revision: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:42.059476) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:42.059909) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:42.060201) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:42.085567) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:42.085860) ========= 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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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-05-13 01:34:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.17M=0.001s 2016-05-13 01:34:42 (9.17 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:42.211541) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:42.211858) ========= 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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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.020086 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:42.263888) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 01:34:42.267269) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 --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 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JXI1b1ABTp/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.uoxR9jSM9z/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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-05-13 01:34:42,349 truncating revision to first 12 chars 2016-05-13 01:34:42,349 Setting DEBUG logging. 2016-05-13 01:34:42,349 attempt 1/10 2016-05-13 01:34:42,349 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/943ca7ad1ea3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 01:34:44,149 unpacking tar archive at: fx-team-943ca7ad1ea3/testing/mozharness/ program finished with exit code 0 elapsedTime=2.058027 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-13 01:34:44.437930) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:44.438268) ========= script_repo_revision: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:44.438666) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:44.438931) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 01:34:44.457022) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-13 01:34:44.457353) ========= /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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01:34:44 INFO - MultiFileLogger online at 20160513 01:34:44 in /builds/slave/test 01:34:44 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 01:34:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:34:44 INFO - {'append_to_log': False, 01:34:44 INFO - 'base_work_dir': '/builds/slave/test', 01:34:44 INFO - 'blob_upload_branch': 'Fx-Team', 01:34:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:34:44 INFO - 'branch': 'Fx-Team', 01:34:44 INFO - 'buildbot_json_path': 'buildprops.json', 01:34:44 INFO - 'config_files': ('talos/mac_config.py',), 01:34:44 INFO - 'default_actions': ('clobber', 01:34:44 INFO - 'read-buildbot-config', 01:34:44 INFO - 'download-and-extract', 01:34:44 INFO - 'populate-webroot', 01:34:44 INFO - 'create-virtualenv', 01:34:44 INFO - 'install', 01:34:44 INFO - 'run-tests'), 01:34:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:34:44 INFO - 'download_minidump_stackwalk': True, 01:34:44 INFO - 'download_symbols': 'ondemand', 01:34:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:34:44 INFO - 'tooltool.py': '/tools/tooltool.py', 01:34:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:34:44 INFO - '/tools/misc-python/virtualenv.py')}, 01:34:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:34:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:34:44 INFO - 'installer_path': 'installer.exe', 01:34:44 INFO - 'log_level': 'info', 01:34:44 INFO - 'log_name': 'talos', 01:34:44 INFO - 'log_to_console': True, 01:34:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:34:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:34:44 INFO - 'opt_config_files': (), 01:34:44 INFO - 'pip_index': False, 01:34:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:34:44 INFO - 'cmd': ('bash', 01:34:44 INFO - '-c', 01:34:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:34:44 INFO - 'enabled': True, 01:34:44 INFO - 'halt_on_failure': False, 01:34:44 INFO - 'name': 'check_screen_resolution'},), 01:34:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:34:44 INFO - 'cmd': ('bash', 01:34:44 INFO - '-c', 01:34:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:34:44 INFO - 'enabled': True, 01:34:44 INFO - 'halt_on_failure': False, 01:34:44 INFO - 'name': 'check_screen_resolution'},), 01:34:44 INFO - 'run_cmd_checks_enabled': True, 01:34:44 INFO - 'sps_profile': False, 01:34:44 INFO - 'sps_profile_interval': 0, 01:34:44 INFO - 'suite': 'dromaeojs', 01:34:44 INFO - 'system_bits': '32', 01:34:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:34:44 INFO - 'title': 't-yosemite-r7-0307', 01:34:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:34:44 INFO - 'use_talos_json': True, 01:34:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:34:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:34:44 INFO - 'work_dir': 'build'} 01:34:44 INFO - ##### 01:34:44 INFO - ##### Running clobber step. 01:34:44 INFO - ##### 01:34:44 INFO - Running pre-action listener: _resource_record_pre_action 01:34:44 INFO - Running main action method: clobber 01:34:44 INFO - rmtree: /builds/slave/test/build 01:34:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:34:46 INFO - Running post-action listener: _resource_record_post_action 01:34:46 INFO - ##### 01:34:46 INFO - ##### Finished clobber step (success) 01:34:46 INFO - ##### 01:34:46 INFO - ##### 01:34:46 INFO - ##### Running read-buildbot-config step. 01:34:46 INFO - ##### 01:34:46 INFO - Running pre-action listener: _resource_record_pre_action 01:34:46 INFO - Running main action method: read_buildbot_config 01:34:46 INFO - Using buildbot properties: 01:34:46 INFO - { 01:34:46 INFO - "project": "", 01:34:46 INFO - "product": "firefox", 01:34:46 INFO - "script_repo_revision": "production", 01:34:46 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:34:46 INFO - "repository": "", 01:34:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:34:46 INFO - "buildid": "20160512224705", 01:34:46 INFO - "pgo_build": "False", 01:34:46 INFO - "basedir": "/builds/slave/test", 01:34:46 INFO - "buildnumber": 815, 01:34:46 INFO - "slavename": "t-yosemite-r7-0307", 01:34:46 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 01:34:46 INFO - "platform": "yosemite_r7", 01:34:46 INFO - "scheduler": "tests-fx-team-macosx64-talos", 01:34:46 INFO - "branch": "fx-team", 01:34:46 INFO - "repo_path": "integration/fx-team", 01:34:46 INFO - "revision": "943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15", 01:34:46 INFO - "stage_platform": "macosx64", 01:34:46 INFO - "builduid": "176baf6029694ac687ff2a990a6d4375", 01:34:46 INFO - "slavebuilddir": "test" 01:34:46 INFO - } 01:34:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 01:34:46 INFO - Running post-action listener: _resource_record_post_action 01:34:46 INFO - ##### 01:34:46 INFO - ##### Finished read-buildbot-config step (success) 01:34:46 INFO - ##### 01:34:46 INFO - ##### 01:34:46 INFO - ##### Running download-and-extract step. 01:34:46 INFO - ##### 01:34:46 INFO - Running pre-action listener: _resource_record_pre_action 01:34:46 INFO - Running main action method: download_and_extract 01:34:46 INFO - mkdir: /builds/slave/test/build/tests 01: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')]} 01:34:46 INFO - https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:34:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:34:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:34:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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 01:34:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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 01:34:47 INFO - Downloaded 1199 bytes. 01:34:47 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:34:47 INFO - Using the following test package requirements: 01:34:47 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:34:47 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:34:47 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 01:34:47 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:34:47 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 01:34:47 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:34:47 INFO - u'jsshell-mac.zip'], 01:34:47 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:34:47 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 01:34:47 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:34:47 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:34:47 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 01:34:47 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:34:47 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 01:34:47 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:34:47 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 01:34:47 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:34:47 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 01:34:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:34:47 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')]} 01:34:47 INFO - https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:34:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:34:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:34:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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 01:34:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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 01:34:48 INFO - Downloaded 18151163 bytes. 01:34:48 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'] 01:34:48 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 01:34:51 INFO - Return code: 0 01:34:51 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 01:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:34:51 INFO - https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:34:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:34:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:34:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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 01:34:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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 01:34:52 INFO - Downloaded 18151163 bytes. 01:34:52 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'] 01:34:52 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 01:34:56 INFO - Return code: 0 01: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')]} 01:34:56 INFO - https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:34:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:34:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:34:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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 01:34:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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 01:34:57 INFO - Downloaded 10840239 bytes. 01:34:57 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'] 01:34:57 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 01:34:57 INFO - Return code: 0 01:34:57 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')]} 01:34:57 INFO - https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:34:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:34:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:34:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 01:34:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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 01:35:04 INFO - Downloaded 109837963 bytes. 01:35:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:35:04 INFO - mkdir: /builds/slave/test/properties 01:35:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:35:04 INFO - Writing to file /builds/slave/test/properties/build_url 01:35:04 INFO - Contents: 01:35:04 INFO - build_url:https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:35:04 INFO - Running post-action listener: _resource_record_post_action 01:35:04 INFO - Running post-action listener: set_extra_try_arguments 01:35:04 INFO - ##### 01:35:04 INFO - ##### Finished download-and-extract step (success) 01:35:04 INFO - ##### 01:35:04 INFO - ##### 01:35:04 INFO - ##### Running populate-webroot step. 01:35:04 INFO - ##### 01:35:04 INFO - Running pre-action listener: _resource_record_pre_action 01:35:04 INFO - Running main action method: populate_webroot 01:35:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:35:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:35:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:35:04 INFO - '--tppagecycles', 01:35:04 INFO - '7'], 01:35:04 INFO - 'tests': ['tsvgm']}}, 01:35:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:35:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:35:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:35:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:35:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:35:04 INFO - 'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:35:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:35:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:35:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:35:04 INFO - 'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['damp', 'tps']}, 01:35:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:35:04 INFO - 'tests': ['damp', 'tps']}, 01:35:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['dromaeo_dom']}, 01:35:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:35:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['a11yr', 01:35:04 INFO - 'ts_paint', 01:35:04 INFO - 'tpaint', 01:35:04 INFO - 'sessionrestore', 01:35:04 INFO - 'sessionrestore_no_auto_restore', 01:35:04 INFO - 'tabpaint']}, 01:35:04 INFO - 'other-e10s': {'tests': ['a11yr', 01:35:04 INFO - 'ts_paint', 01:35:04 INFO - 'tpaint', 01:35:04 INFO - 'sessionrestore', 01:35:04 INFO - 'sessionrestore_no_auto_restore', 01:35:04 INFO - 'tabpaint']}, 01:35:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:35:04 INFO - 'ts_paint', 01:35:04 INFO - 'tpaint', 01:35:04 INFO - 'sessionrestore', 01:35:04 INFO - 'sessionrestore_no_auto_restore', 01:35:04 INFO - 'tabpaint']}, 01:35:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:35:04 INFO - 'ts_paint', 01:35:04 INFO - 'tpaint', 01:35:04 INFO - 'sessionrestore', 01:35:04 INFO - 'sessionrestore_no_auto_restore', 01:35:04 INFO - 'tabpaint']}, 01:35:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['a11yr', 01:35:04 INFO - 'ts_paint', 01:35:04 INFO - 'tpaint', 01:35:04 INFO - 'sessionrestore', 01:35:04 INFO - 'sessionrestore_no_auto_restore', 01:35:04 INFO - 'tabpaint']}, 01:35:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['a11yr', 01:35:04 INFO - 'ts_paint', 01:35:04 INFO - 'tpaint', 01:35:04 INFO - 'sessionrestore', 01:35:04 INFO - 'sessionrestore_no_auto_restore', 01:35:04 INFO - 'tabpaint']}, 01:35:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['tsvgx', 01:35:04 INFO - 'tsvgr_opacity', 01:35:04 INFO - 'tart', 01:35:04 INFO - 'tscrollx', 01:35:04 INFO - 'cart']}, 01:35:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:35:04 INFO - 'tsvgr_opacity', 01:35:04 INFO - 'tart', 01:35:04 INFO - 'tscrollx', 01:35:04 INFO - 'cart']}, 01:35:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:35:04 INFO - 'talos_options': ['--disable-e10s'], 01:35:04 INFO - 'tests': ['tp5o']}, 01:35:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:35:04 INFO - 'tests': ['tp5o']}, 01:35:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:35:04 INFO - 'talos_options': ['--disable-e10s', 01:35:04 INFO - '--xperf_path', 01:35:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:35:04 INFO - 'tests': ['tp5n']}, 01:35:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:35:04 INFO - 'talos_options': ['--xperf_path', 01:35:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:35:04 INFO - 'tests': ['tp5n']}}, 01:35:04 INFO - 'talos.zip': {'path': '', 01:35:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:35:04 INFO - Running post-action listener: _resource_record_post_action 01:35:04 INFO - ##### 01:35:04 INFO - ##### Finished populate-webroot step (success) 01:35:04 INFO - ##### 01:35:04 INFO - ##### 01:35:04 INFO - ##### Running create-virtualenv step. 01:35:04 INFO - ##### 01:35:04 INFO - Running pre-action listener: _resource_record_pre_action 01:35:04 INFO - Running main action method: create_virtualenv 01:35:04 INFO - Creating virtualenv /builds/slave/test/build/venv 01:35:04 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 01:35:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:35:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:35:04 INFO - Using real prefix '/tools/python27' 01:35:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:35:05 INFO - Installing distribute.............................................................................................................................................................................................done. 01:35:08 INFO - Installing pip.................done. 01:35:08 INFO - Return code: 0 01:35:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:35:08 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')]} 01:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:08 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')]} 01:35:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:08 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 0x100d86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd41c95230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, '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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01:35:08 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 01:35:08 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 01:35:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:35:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:08 INFO - 'HOME': '/Users/cltbld', 01:35:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:08 INFO - 'LOGNAME': 'cltbld', 01:35:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:08 INFO - 'MOZ_NO_REMOTE': '1', 01:35:08 INFO - 'NO_EM_RESTART': '1', 01:35:08 INFO - 'PAGER': '/bin/cat', 01:35:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:08 INFO - 'PWD': '/builds/slave/test', 01:35:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:08 INFO - 'SHELL': '/bin/bash', 01:35:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:35:08 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:35:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:08 INFO - 'USER': 'cltbld', 01:35:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:08 INFO - 'XPC_FLAGS': '0x0', 01:35:08 INFO - 'XPC_SERVICE_NAME': '0', 01:35:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:08 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 01:35:08 INFO - Cleaning up... 01:35:08 INFO - Return code: 0 01:35:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:35:08 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')]} 01:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:08 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')]} 01:35:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd41c95230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, '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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01:35:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 01:35:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 01:35:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:35:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:08 INFO - 'HOME': '/Users/cltbld', 01:35:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:08 INFO - 'LOGNAME': 'cltbld', 01:35:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:08 INFO - 'MOZ_NO_REMOTE': '1', 01:35:08 INFO - 'NO_EM_RESTART': '1', 01:35:08 INFO - 'PAGER': '/bin/cat', 01:35:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:08 INFO - 'PWD': '/builds/slave/test', 01:35:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:08 INFO - 'SHELL': '/bin/bash', 01:35:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:35:08 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:35:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:08 INFO - 'USER': 'cltbld', 01:35:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:08 INFO - 'XPC_FLAGS': '0x0', 01:35:08 INFO - 'XPC_SERVICE_NAME': '0', 01:35:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:09 INFO - Downloading/unpacking psutil>=0.7.1 01: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 01: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 01: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 01: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 01: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 01: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 01:35:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:35:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:35:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:35:11 INFO - Installing collected packages: psutil 01:35:11 INFO - Running setup.py install for psutil 01:35:12 INFO - building 'psutil._psutil_osx' extension 01:35:12 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 01:35:12 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 01:35:12 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 01:35:12 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 01:35:12 INFO - building 'psutil._psutil_posix' extension 01:35:12 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 01:35:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:35:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:35:12 INFO - ^ 01:35:12 INFO - 1 warning generated. 01:35:12 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 01:35:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:35:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:35:12 INFO - Successfully installed psutil 01:35:12 INFO - Cleaning up... 01:35:12 INFO - Return code: 0 01:35:12 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01: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')]} 01:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01: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')]} 01:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd41c95230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, '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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 01: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 mozsystemmonitor==0.0.0 01:35:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:35:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:12 INFO - 'HOME': '/Users/cltbld', 01:35:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:12 INFO - 'LOGNAME': 'cltbld', 01:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:12 INFO - 'MOZ_NO_REMOTE': '1', 01:35:12 INFO - 'NO_EM_RESTART': '1', 01:35:12 INFO - 'PAGER': '/bin/cat', 01:35:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:12 INFO - 'PWD': '/builds/slave/test', 01:35:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:12 INFO - 'SHELL': '/bin/bash', 01:35:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:35:12 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:35:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:12 INFO - 'USER': 'cltbld', 01:35:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:12 INFO - 'XPC_FLAGS': '0x0', 01:35:12 INFO - 'XPC_SERVICE_NAME': '0', 01:35:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:35:13 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 01:35:13 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 01:35:13 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 01:35:13 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 01:35:13 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 01:35:13 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 01:35:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:35:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:35:15 INFO - Installing collected packages: mozsystemmonitor 01:35:15 INFO - Running setup.py install for mozsystemmonitor 01:35:15 INFO - Successfully installed mozsystemmonitor 01:35:15 INFO - Cleaning up... 01:35:15 INFO - Return code: 0 01:35:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:35:15 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')]} 01:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:15 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')]} 01:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:15 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 0x100d86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd41c95230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, '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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01:35:15 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 01:35:15 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 01:35:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:35:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:15 INFO - 'HOME': '/Users/cltbld', 01:35:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:15 INFO - 'LOGNAME': 'cltbld', 01:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:15 INFO - 'MOZ_NO_REMOTE': '1', 01:35:15 INFO - 'NO_EM_RESTART': '1', 01:35:15 INFO - 'PAGER': '/bin/cat', 01:35:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:15 INFO - 'PWD': '/builds/slave/test', 01:35:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:15 INFO - 'SHELL': '/bin/bash', 01:35:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:35:15 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:35:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:15 INFO - 'USER': 'cltbld', 01:35:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:15 INFO - 'XPC_FLAGS': '0x0', 01:35:15 INFO - 'XPC_SERVICE_NAME': '0', 01:35:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:16 INFO - Downloading/unpacking blobuploader==1.2.4 01: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 01: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 01: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 01: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 01: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 01: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 01:35:18 INFO - Downloading blobuploader-1.2.4.tar.gz 01:35:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:35:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:35:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:35:19 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 01:35:19 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 01:35:19 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 01:35:19 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 01:35:19 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 01:35:19 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 01:35:19 INFO - Downloading docopt-0.6.1.tar.gz 01:35:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:35:19 INFO - Installing collected packages: blobuploader, requests, docopt 01:35:19 INFO - Running setup.py install for blobuploader 01:35:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:35:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:35:19 INFO - Running setup.py install for requests 01:35:20 INFO - Running setup.py install for docopt 01:35:20 INFO - Successfully installed blobuploader requests docopt 01:35:20 INFO - Cleaning up... 01:35:20 INFO - Return code: 0 01:35:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:35:20 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')]} 01:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:20 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')]} 01:35:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:20 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 0x100d86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd41c95230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, '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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01:35:20 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 01:35:20 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 01:35:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:35:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:20 INFO - 'HOME': '/Users/cltbld', 01:35:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:20 INFO - 'LOGNAME': 'cltbld', 01:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:20 INFO - 'MOZ_NO_REMOTE': '1', 01:35:20 INFO - 'NO_EM_RESTART': '1', 01:35:20 INFO - 'PAGER': '/bin/cat', 01:35:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:20 INFO - 'PWD': '/builds/slave/test', 01:35:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:20 INFO - 'SHELL': '/bin/bash', 01:35:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:35:20 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:35:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:20 INFO - 'USER': 'cltbld', 01:35:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:20 INFO - 'XPC_FLAGS': '0x0', 01:35:20 INFO - 'XPC_SERVICE_NAME': '0', 01:35:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:35:20 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-RVqFfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:35:20 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-jkftLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-Q8hRw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-1t8r9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-BwOkXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-UemfR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-HB_r1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-nBIeq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-KVhzUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-Syc0hX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-1f32Up-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:35:21 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-Ijs65c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:35:22 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-pJurwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:35:22 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-0f7tGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:22 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-SFLK05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:35:22 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-ovuKrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:35:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:35:22 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-JSs8iU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:35:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:35:22 INFO - Running setup.py install for manifestparser 01:35:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:35:22 INFO - Running setup.py install for mozcrash 01:35:22 INFO - Running setup.py install for mozdebug 01:35:23 INFO - Running setup.py install for mozdevice 01:35:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Running setup.py install for mozfile 01:35:23 INFO - Running setup.py install for mozhttpd 01:35:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Running setup.py install for mozinfo 01:35:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Running setup.py install for mozInstall 01:35:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:35:23 INFO - Running setup.py install for mozleak 01:35:23 INFO - Running setup.py install for mozlog 01:35:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:35:24 INFO - Running setup.py install for moznetwork 01:35:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:35:24 INFO - Running setup.py install for mozprocess 01:35:24 INFO - Running setup.py install for mozprofile 01:35:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:35:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:35:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:35:24 INFO - Running setup.py install for mozrunner 01:35:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:35:24 INFO - Running setup.py install for mozscreenshot 01:35:24 INFO - Running setup.py install for moztest 01:35:25 INFO - Running setup.py install for mozversion 01:35:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:35:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:35:25 INFO - Cleaning up... 01:35:25 INFO - Return code: 0 01:35:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:35:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:35:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:25 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 0x100d86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd41c95230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, '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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01:35:25 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 01:35:25 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 01:35:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:35:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:25 INFO - 'HOME': '/Users/cltbld', 01:35:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:25 INFO - 'LOGNAME': 'cltbld', 01:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:25 INFO - 'MOZ_NO_REMOTE': '1', 01:35:25 INFO - 'NO_EM_RESTART': '1', 01:35:25 INFO - 'PAGER': '/bin/cat', 01:35:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:25 INFO - 'PWD': '/builds/slave/test', 01:35:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:25 INFO - 'SHELL': '/bin/bash', 01:35:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:35:25 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:35:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:25 INFO - 'USER': 'cltbld', 01:35:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:25 INFO - 'XPC_FLAGS': '0x0', 01:35:25 INFO - 'XPC_SERVICE_NAME': '0', 01:35:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:35:25 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-9hfVTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:35:25 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-7LtBXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:35:25 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-rUtnQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:35:25 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-5sIs6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:35:25 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-FvEl1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:35:25 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)) 01:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:35:25 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-_BcYHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:35:26 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)) 01:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:35:26 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-sG8x0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:35:26 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)) 01:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:35:26 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-XzypJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:35:26 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)) 01:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:35:26 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-qxfOCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:35:26 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)) 01:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:35:26 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-Y1Tniu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:35:26 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)) 01:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:35:26 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-yV7dqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:35:26 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)) 01:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:35:26 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-sqMXZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:35:26 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)) 01:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:35:26 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-zU5Fzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:35:26 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)) 01:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:35:26 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-QOpLzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:35:26 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)) 01:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:26 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-wEZhJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:35:27 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)) 01:35:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:35:27 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-g7P9A6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:35:27 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)) 01:35:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:35:27 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-41Xxwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:35:27 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)) 01:35:27 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)) 01:35:27 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)) 01:35:27 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)) 01: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 mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:35:27 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)) 01:35:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01: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 01: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 01: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 01: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 01: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 01: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 01:35:29 INFO - Downloading blessings-1.6.tar.gz 01:35:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:35:29 INFO - Installing collected packages: blessings 01:35:29 INFO - Running setup.py install for blessings 01:35:29 INFO - Successfully installed blessings 01:35:29 INFO - Cleaning up... 01:35:30 INFO - Return code: 0 01:35:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:35:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:35:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:35:30 INFO - Reading from file tmpfile_stdout 01:35:30 INFO - Current package versions: 01:35:30 INFO - blessings == 1.6 01:35:30 INFO - blobuploader == 1.2.4 01:35:30 INFO - docopt == 0.6.1 01:35:30 INFO - manifestparser == 1.1 01:35:30 INFO - mozInstall == 1.12 01:35:30 INFO - mozcrash == 0.17 01:35:30 INFO - mozdebug == 0.1 01:35:30 INFO - mozdevice == 0.48 01:35:30 INFO - mozfile == 1.2 01:35:30 INFO - mozhttpd == 0.7 01:35:30 INFO - mozinfo == 0.9 01:35:30 INFO - mozleak == 0.1 01:35:30 INFO - mozlog == 3.2 01:35:30 INFO - moznetwork == 0.27 01:35:30 INFO - mozprocess == 0.22 01:35:30 INFO - mozprofile == 0.28 01:35:30 INFO - mozrunner == 6.11 01:35:30 INFO - mozscreenshot == 0.1 01:35:30 INFO - mozsystemmonitor == 0.0 01:35:30 INFO - moztest == 0.7 01:35:30 INFO - mozversion == 1.4 01:35:30 INFO - psutil == 3.1.1 01:35:30 INFO - requests == 1.2.3 01:35:30 INFO - wsgiref == 0.1.2 01:35:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:35:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:35:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:30 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 0x100d86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd41c95230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, '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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01:35:30 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 01:35:30 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 01:35:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:35:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:30 INFO - 'HOME': '/Users/cltbld', 01:35:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:30 INFO - 'LOGNAME': 'cltbld', 01:35:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:30 INFO - 'MOZ_NO_REMOTE': '1', 01:35:30 INFO - 'NO_EM_RESTART': '1', 01:35:30 INFO - 'PAGER': '/bin/cat', 01:35:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:30 INFO - 'PWD': '/builds/slave/test', 01:35:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:30 INFO - 'SHELL': '/bin/bash', 01:35:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:35:30 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:35:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:30 INFO - 'USER': 'cltbld', 01:35:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:30 INFO - 'XPC_FLAGS': '0x0', 01:35:30 INFO - 'XPC_SERVICE_NAME': '0', 01:35:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:30 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)) 01:35:30 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)) 01:35:30 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)) 01:35:30 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)) 01:35:30 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)) 01:35:30 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)) 01:35:30 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)) 01:35:30 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)) 01:35:30 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)) 01:35:30 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)) 01:35:30 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)) 01:35:30 INFO - Cleaning up... 01:35:30 INFO - Return code: 0 01:35:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:35:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:35:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:30 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 0x100d86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd41c95230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, '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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01:35:30 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 01:35:30 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 01:35:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:35:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:30 INFO - 'HOME': '/Users/cltbld', 01:35:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:30 INFO - 'LOGNAME': 'cltbld', 01:35:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:30 INFO - 'MOZ_NO_REMOTE': '1', 01:35:30 INFO - 'NO_EM_RESTART': '1', 01:35:30 INFO - 'PAGER': '/bin/cat', 01:35:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:30 INFO - 'PWD': '/builds/slave/test', 01:35:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:30 INFO - 'SHELL': '/bin/bash', 01:35:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:35:30 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:35:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:30 INFO - 'USER': 'cltbld', 01:35:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:30 INFO - 'XPC_FLAGS': '0x0', 01:35:30 INFO - 'XPC_SERVICE_NAME': '0', 01:35:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:30 INFO - Downloading/unpacking jsonschema 01: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 01: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 01: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 01: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 01: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 01: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 01:35:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:35:33 INFO - Downloading/unpacking functools32 (from jsonschema) 01: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 01: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 01: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 01: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 01: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 01: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 01:35:33 INFO - Downloading functools32-3.2.3-2.tar.gz 01:35:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:35:33 INFO - warning: no files found matching '*.txt' 01:35:33 INFO - no previously-included directories found matching 'build' 01:35:33 INFO - no previously-included directories found matching 'dist' 01:35:33 INFO - no previously-included directories found matching '.git*' 01:35:33 INFO - Installing collected packages: jsonschema, functools32 01:35:33 INFO - Running setup.py install for functools32 01:35:33 INFO - warning: no files found matching '*.txt' 01:35:34 INFO - no previously-included directories found matching 'build' 01:35:34 INFO - no previously-included directories found matching 'dist' 01:35:34 INFO - no previously-included directories found matching '.git*' 01:35:34 INFO - Successfully installed jsonschema functools32 01:35:34 INFO - Cleaning up... 01:35:34 INFO - Return code: 0 01:35:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:35:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:35:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:35:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:35:34 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 0x100d86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d8c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd41c95230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, '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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 01:35:34 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 01:35:34 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 01:35:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:35:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:35:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:35:34 INFO - 'HOME': '/Users/cltbld', 01:35:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:35:34 INFO - 'LOGNAME': 'cltbld', 01:35:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:35:34 INFO - 'MOZ_NO_REMOTE': '1', 01:35:34 INFO - 'NO_EM_RESTART': '1', 01:35:34 INFO - 'PAGER': '/bin/cat', 01:35:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:35:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:35:34 INFO - 'PWD': '/builds/slave/test', 01:35:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:35:34 INFO - 'SHELL': '/bin/bash', 01:35:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:35:34 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:35:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:35:34 INFO - 'USER': 'cltbld', 01:35:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:35:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:35:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:35:34 INFO - 'XPC_FLAGS': '0x0', 01:35:34 INFO - 'XPC_SERVICE_NAME': '0', 01:35:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:35:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:35:34 INFO - Downloading/unpacking flake8 01: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 01: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 01: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 01: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 01: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 01: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 01:35:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:35:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:35:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:35:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:35:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:35:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:35:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:35:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:35:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:35:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:35:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:35:38 INFO - no previously-included directories found matching 'docs/_build' 01:35:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:35:38 INFO - Running setup.py install for pep8 01:35:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:35:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:35:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:35:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:35:38 INFO - no previously-included directories found matching 'docs/_build' 01:35:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:35:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:35:38 INFO - Cleaning up... 01:35:38 INFO - Return code: 0 01:35:38 INFO - Running post-action listener: _resource_record_post_action 01:35:38 INFO - Running post-action listener: _start_resource_monitoring 01:35:38 INFO - Starting resource monitoring. 01:35:38 INFO - ##### 01:35:38 INFO - ##### Finished create-virtualenv step (success) 01:35:38 INFO - ##### 01:35:38 INFO - ##### 01:35:38 INFO - ##### Running install step. 01:35:38 INFO - ##### 01:35:38 INFO - Running pre-action listener: _resource_record_pre_action 01:35:38 INFO - Running main action method: install 01:35:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:35:38 INFO - Reading from file tmpfile_stdout 01:35:38 INFO - Detecting whether we're running mozinstall >=1.0... 01:35:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:35:38 INFO - Reading from file tmpfile_stdout 01:35:38 INFO - Output received: 01:35:38 INFO - Usage: mozinstall [options] installer 01:35:38 INFO - Options: 01:35:38 INFO - -h, --help show this help message and exit 01:35:38 INFO - -d DEST, --destination=DEST 01:35:38 INFO - Directory to install application into. [default: 01:35:38 INFO - "/builds/slave/test"] 01:35:38 INFO - --app=APP Application being installed. [default: firefox] 01:35:38 INFO - mkdir: /builds/slave/test/build/application 01:35:38 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'] 01:35:38 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 01:36:12 INFO - Reading from file tmpfile_stdout 01:36:12 INFO - Output received: 01:36:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:36:12 INFO - Running post-action listener: _resource_record_post_action 01:36:12 INFO - ##### 01:36:12 INFO - ##### Finished install step (success) 01:36:12 INFO - ##### 01:36:12 INFO - ##### 01:36:12 INFO - ##### Running run-tests step. 01:36:12 INFO - ##### 01:36:12 INFO - Running pre-action listener: _resource_record_pre_action 01:36:12 INFO - Running main action method: run_tests 01:36:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:36:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:36:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:36:12 INFO - 2016-05-13 01:36:12.943 screenresolution[1972:10141] starting screenresolution argv=screenresolution get 01:36:12 INFO - 2016-05-13 01:36:12.946 screenresolution[1972:10141] Display 0: 1600x1200x32@60 01:36:12 INFO - 2016-05-13 01:36:12.951 screenresolution[1973:10143] starting screenresolution argv=screenresolution list 01:36:12 INFO - Available Modes on Display 0 01:36:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:36:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:36:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:36:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:36:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:36:13 INFO - Intel Iris: 01:36:13 INFO - Chipset Model: Intel Iris 01:36:13 INFO - Type: GPU 01:36:13 INFO - Bus: Built-In 01:36:13 INFO - VRAM (Dynamic, Max): 1536 MB 01:36:13 INFO - Vendor: Intel (0x8086) 01:36:13 INFO - Device ID: 0x0a2e 01:36:13 INFO - Revision ID: 0x0009 01:36:13 INFO - Displays: 01:36:13 INFO - Display: 01:36:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:36:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:36:13 INFO - Display Serial Number: 1600x1200 60 01:36:13 INFO - Main Display: Yes 01:36:13 INFO - Mirror: Off 01:36:13 INFO - Online: Yes 01:36:13 INFO - Rotation: Supported 01:36:13 INFO - Return code: 0 01:36:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:36:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:36:13 INFO - Python 2.7.3 01:36:13 INFO - Return code: 0 01:36:13 INFO - grabbing minidump binary from tooltool 01:36:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:36:13 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 0x100c2ad30>, '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 0x100a4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c21b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:36:13 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 01:36:13 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 01:36:13 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 01:36:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:36:13 INFO - Return code: 0 01:36:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:36:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:36:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:36:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:36:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:36:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:36:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:36:14 INFO - Return code: 0 01:36:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0307', '--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 01:36:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0307 --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 01:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JXI1b1ABTp/Render', 01:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:14 INFO - 'HOME': '/Users/cltbld', 01:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:14 INFO - 'LOGNAME': 'cltbld', 01:36:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:36:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:14 INFO - 'MOZ_NO_REMOTE': '1', 01:36:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:36:14 INFO - 'NO_EM_RESTART': '1', 01:36:14 INFO - 'PAGER': '/bin/cat', 01:36:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:14 INFO - 'PWD': '/builds/slave/test', 01:36:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:14 INFO - 'SHELL': '/bin/bash', 01:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uoxR9jSM9z/Listeners', 01:36:14 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 01:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:14 INFO - 'USER': 'cltbld', 01:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:14 INFO - 'XPC_FLAGS': '0x0', 01:36:14 INFO - 'XPC_SERVICE_NAME': '0', 01:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0307', '--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 01:36:14 INFO - mozversion application_buildid: 20160512224705 01:36:14 INFO - mozversion application_changeset: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 01:36:14 INFO - mozversion application_display_name: Nightly 01:36:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:36:14 INFO - mozversion application_name: Firefox 01:36:14 INFO - mozversion application_remotingname: firefox 01:36:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:36:14 INFO - mozversion application_vendor: Mozilla 01:36:14 INFO - mozversion application_version: 49.0a1 01:36:14 INFO - mozversion platform_buildid: 20160512224705 01:36:14 INFO - mozversion platform_changeset: 943ca7ad1ea35e01a6ba6bfa0a4f3896f1150e15 01:36:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:36:14 INFO - mozversion platform_version: 49.0a1 01:36:14 INFO - using testdate: 1463128574 01:36:14 INFO - actual date: 1463128574 01:36:14 INFO - starting webserver on 'localhost:49385' 01:36:14 INFO - SUITE-START | Running 2 tests 01:36:14 INFO - TEST-START | dromaeo_css 01:36:14 INFO - operating with platform_type : mac_ 01:36:14 INFO - Initialising browser for dromaeo_css test... 01:36:14 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/tmpyvtmiN/profile http://localhost:49385/getInfo.html) 01:36:16 INFO - PROCESS | 1991 | __metrics Screen width/height:1600/1200 01:36:16 INFO - PROCESS | 1991 | colorDepth:24 01:36:16 INFO - PROCESS | 1991 | Browser inner width/height: 1024/689 01:36:16 INFO - PROCESS | 1991 | __metrics 01:36:16 INFO - PROCESS | 1991 | 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" 01:36:16 ERROR - PROCESS | 1991 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:36:17 INFO - TEST-INFO | 1991: exit 0 01:36:17 INFO - Browser initialized. 01:36:17 INFO - Running cycle 1/1 for dromaeo_css test... 01:36:17 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/tmpyvtmiN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:36:29 INFO - PROCESS | 1993 | RSS: Main: 239071232 01:36:29 INFO - PROCESS | 1993 | 01:37:20 INFO - PROCESS | 1993 | 1463128640449 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] 01:39:31 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49385/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49385/tests/dromaeo/cssquery-ext.html) 01:39:32 INFO - PROCESS | 1993 | RSS: Main: 369897472 01:39:32 INFO - PROCESS | 1993 | 01:42:35 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49385/tests/dromaeo/cssquery-ext.html (next: http://localhost:49385/tests/dromaeo/cssquery-jquery.html) 01:42:36 INFO - PROCESS | 1993 | RSS: Main: 367304704 01:42:36 INFO - PROCESS | 1993 | 01:45:38 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49385/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49385/tests/dromaeo/cssquery-mootools.html) 01:45:39 INFO - PROCESS | 1993 | RSS: Main: 371822592 01:45:39 INFO - PROCESS | 1993 | 01:48:41 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49385/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49385/tests/dromaeo/cssquery-prototype.html) 01:48:42 INFO - PROCESS | 1993 | RSS: Main: 369221632 01:48:42 INFO - PROCESS | 1993 | 01:51:45 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49385/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49385/tests/dromaeo/cssquery-yui.html) 01:51:46 INFO - PROCESS | 1993 | RSS: Main: 337264640 01:51:46 INFO - PROCESS | 1993 | 01:54:49 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49385/tests/dromaeo/cssquery-yui.html (next: http://localhost:49385/tests/dromaeo/cssquery-dojo.html) 01:54:49 INFO - PROCESS | 1993 | RSS: Main: 399769600 01:54:49 INFO - PROCESS | 1993 | 01:54:49 INFO - PROCESS | 1993 | __start_tp_report 01:54:49 INFO - PROCESS | 1993 | _x_x_mozilla_page_load 01:54:49 INFO - PROCESS | 1993 | _x_x_mozilla_page_load_details 01:54:49 INFO - PROCESS | 1993 | |i|pagename|runs| 01:54:49 INFO - PROCESS | 1993 | |0;dojo.html;3389.2713066690662;3392.762506624536;3414.385410626087;3430.8627654893808;3460.9307813843725;5656.377798442168;5672.347680016799;5701.401352857947;5798.971005144974;5830.475257226849;5755.0504166812425;5757.1652110444065;5790.884182316351;5833.154192642064;5863.442972917566;5093.465186155456;5145.33110695611;5194.298769666103;5215.348081489814;5295.523402893738;5266.394364648077;5313.760880760375;5318.787248510055;5320.9467905321035;5356.732163391835;5098.362704661918;5168.302279192299;5174.844754657366;5221.973890130544;5223.451537588575;5210.76551555181;5219.817306394296;5223.660462069991;5230.450802665741;5233.267342572043;5080.755214972355;5102.719350435725;5177.611679124043;5183.196604526304;5202.167653175474;4999.625028122869;5567.582431317667;5592.86017849553;5626.9437305626825;5657.9434205658235;6103.169049285197;6145.955187618071;6149.723262453201;6169.691515424256;6177.536684748662;6043.2748070231855;6105.32841387447;6123.510119190499;6199.411055949678;6209.565330426872;4857.247126695333;5826.329972053268;5846.736896839652;5847.970760146257;5855.180274761537;6209.844753881099;6232.937670623236;6241.34465881085;6246.781362652286;6251.343608921089;5467.152591348347;5948.970255148696;6002.369751176064;6139.693015349215;6146.4775494082605;6140.964409184515;6171.259448866164;6173.32093469718;6227.813165605039;6243.094751261042;6179.28938172107;6189.288231853305;6217.751289948352;6219.968900155471;6234.438900498977;6101.206843110367;6139.277491147187;6153.753849846045;6223.346548612421;6225.8132256032395;4851.102546028996;4878.609711223094;4888.828890988798;4900.068986892479;4902.803887844446;3453.792772433662;4379.802908869108;4380.890477738082;4393.912121757547;4428.778561071934;5787.8553036174435;5795.362510123883;5809.0705487122705;5815.069588865846;5819.94180058194;6136.8772624547255;6147.877042459126;6165.537584681167;6193.473554747897;6214.316425193225;5754.367019627837;5762.88474230513;5763.991301522216;5773.076307790733;5801.94198058014;5249.973750131225;5315.149576067811;5378.139497680352;5401.189821526802;5402.945970540244;6186.752529898754;6217.160683307696;6239.407256310734;6278.277998030102;6344.841378965562;6179.598326108789;6181.350958149509;6186.133941248138;6204.751809927552;6227;5229.450907654642;6288.654124023223;6301.527385446018;6315.96842015787;6327.272363678236;6056.818295451144;6106.5114790817515;6115.57501430398;6161.199044124235;6179.412955769195;6170.629762214281;6193.040078787795;6213.254409470893;6213.409726076015;6237.875242495215;5933.525317974638;5984.461398474158;5996.019199173934;6018.879622407615;6061.636301821904;6034.9094763577705;6114.753609057623;6120.355709576858;6123.038605230386;6141.455323060992;5999.670018149044;6002.249718785152;6008.579399441997;6038;6047.485963693135;5889.528837692888;5953.732082056231;6000.8499787503915;6028;6108.633481991094;5916.615419997686;5951.464368206882;5968.074948383008;5968.552358573212;5993 01:54:49 INFO - PROCESS | 1993 | |1;ext.html;494.0884068892893;498.1133582223643;501.8318863180832;513.9383274007118;539.7948779463803;30637.540436893465;30937.535936960936;32617.347653046923;41084.38373424401;42397.7880110599;43363.566364336344;43822.68531944047;43983.78008109955;44027.33958990618;44234.77882610582;9701.27240456965;9874.753131171707;9896.356736812084;9900.801983960317;9905.15806156474;8680.8697869532;8839.646414143426;8867.379283450162;8898.466092034467;9575.377600455948;8713.823633809418;11767.176297659167;11962.401879906014;12010.759784804342;12046.879531204708;11854.64436066915;11954.701132471715;11964.222325548812;11991.800819918026;12010.03919686423;8581.485110893367;11643.126765492538;11725.179237453382;11829.231099978473;11830.585929492427;38417.23165536673;40620.98447538805;40625.79687101545;40651;40725.59274407248;3257.2833976525126;3397.541331920184;3438.157651375429;3550.414181660023;3610.1335679436747;28372.86508539656;28392.716072839215;28975;29258.122256332343;29260.8536957316;2718.3340081679917;2725.4957832800796;2730.180945716297;2765.764909982654;2829.9717002829916;377946.99999999726;378638;378749.1062544697;379166;379695;51601.74199129055;58628.36172372253;58835.11747323794;60231.39768602258;60416;62646.68676656679;62668.686656566424;62921;62946.685266573375;63049.68475157687;15377.308021139075;15565.455209067855;15571.688566228839;15577.98743081696;15585.142817144939;12151.331676757687;12236.81644775329;12259.264444133381;12263.141580089487;12423.192492487959;14042.578722638336;14399.208043557495;15415.306311216022;15599;15657;6330.905036424458;6460.289368169498;6498.837529061811;6508.251551071593;6515.2181738190775;919.595378033663;959.4627008875052;1022.1107636356269;1038.1383451735041;1135.1543100390097;25783.710814459202;28883.56674649882;29282.648559251542;29671.554926676115;29702.702972969993;1035.321864178964;1078.6170909327234;1102.5755084292605;1172.3200543684643;1184.5498710489958;14938.70122597542;15013.024153429991;15076.095434273759;15322.616934576501;15441.382344706311;563017;566526;567556;568489;568639;56608;57888;57966;57985;58098.7095064522;1467.4057006912217;1480.4448331875547;1485.2870622101668;1500.932458039369;1507.0279669613037;22930.082796687944;28231.43537129287;28801.711982879904;29289.85355073296;29301.70698293075;49408;49854;50031;50040;50142.24786628131;504538;506978;507091;508729;508823;29082.709172908;29555.261118472212;38679.80660096682;38959.415608765325;39663.00842478846;34295.657043429244;38268.80865595654;45143.548564513934;47720.7613961928;49162;36907.44638830366;37013;37294.06764830901;37701.434478483396;38084.23831523409;35945.64054359423;36176.09559761027;36304.81847590745;36935.63064369322;37183;35402.93791186268;35906.28187436289;36088.45867312045;36660.45009324916;36701.53193872322;24749.752502474745;30165.849170754005;32547.511787322735;32695.836520817244;33370.33259334848;24318.392040199138;24504.38739031467;24746.50506989886;24787.380315492257;24888.626670599595 01:54:49 INFO - PROCESS | 1993 | |2;jquery.html;390.45336528859593;395.2569169960473;398.5695448915172;403.1815414708142;422.9450171477707;14327.928360358184;20028.4992875178;20648.690269645966;21091.367258982234;21128.366149015517;15947.84052159478;17637.382691605795;20343.982800859936;20601.896990515062;20710;5596.748146333414;5864;5876.118582212681;5886.205362276102;5886.970565147169;5668.549529949521;5671.291088613923;5747.109198074284;5749.108888122348;5756.079027355624;4000.359942409215;4218.852340168095;4220.324748040314;4230.555791641879;4234.21666991606;4219.578042195786;4232.76719780412;5957.672328021957;6305.117283580303;6314.116023756677;5250.658707184059;5530.917036244419;5541.833744987657;5545.556355491551;5554.805581804618;22608.208712694977;22814.771852281432;25555.872220638965;25941.481170376486;25981.090661826933;2152.763196048433;2155.9784402155933;2158.9568208635897;2321.814254859607;2796.2869468285458;14595.78106328406;14778.704425911528;14798.260086995608;15071.020383675026;15279.770803437958;1764.5676809181705;1772.6277481728853;1815.4190658989128;1849.3249963763265;1872.2885303584687;136862;145350;149656;150542.87789974397;156362.21818890946;20820.37538873836;20937.581248374947;21058.894705526374;21203.36389908305;21287.8935605321;27894.16317510478;34585.48121778175;34719.82640086783;34811.78158764431;34870;10620.362778233331;10829.83755243672;10834.512446939907;10864.511097000654;10978;8801.207891289814;8801.691940782042;8857.689980850639;8937.597808098652;8947.686830960885;8025.478343907744;9321.533923303808;9645.37305075168;9724.319297649237;9740.707778766648;3703.0929071528185;3798.8290526926353;3810.8666196682984;3883.9997401338333;3898.641322000149;891.7057371067532;914.131575003749;923.3075193604797;959.9904000960041;1029.4080903480528;18788.779602178794;19699.803001969798;19753;19755.60488790245;19901.900490497454;925.8472352061834;939.9248060155307;969.9612015519441;1006.3861044762689;1028.1620479309338;10557.366558006544;10567.418791966362;10571.841422378671;10587.735306617242;10589.523471443803;569419;573207;586184;601457;601695;122160.77839221494;127815.36092319665;133561;133868.33065834804;135045;1008.5814387029301;1017.9643712470028;1060.2631171336002;1087.858578384805;1088.4910281800526;14523.910706696828;16889.239984200496;17529;17776.822231777518;17785;38175.6182438172;49497.75251123722;50039.24941125959;50504;50661.74669126631;59445;74150;75832.62083689547;76745;77986;8229.670813167406;8233.259006689426;8279.83440331178;8301.875471868048;8610.095939926217;11802.291862488279;12210;12390.504379824706;12514.624561263177;12537.686557835763;10573.9471302643;10927.180461465263;10989.120870330515;11102.500387482422;11180.832287515532;10431.16550675929;10482.842757358798;10648.094911932572;10732.248742587944;10907.727306817393;10955.452227388758;11073.94463027712;11080.445977700922;11358.772824543628;11376.033037191932;7199.424046076197;8025.9197408025175;8228.876566851382;8254.958725206336;8255.958720206361;8369.581520924052;10243.94878025605;10798.082163015917;11035.558577656804;11083.50124244427 01:54:49 INFO - PROCESS | 1993 | |3;mootools.html;1435.1245740098545;1583.0897234090398;1619.9757003644943;1856.6286742651473;1994.910229039694;8015.0381954165505;8768.956155219223;9014.323925705565;9049.547522623876;9150.588223529941;8778.780530486742;8985.865212021808;9002.54987250638;9009.819803603925;9013.41412808167;2113.186423227061;2197.8351623628214;2198.56028794241;2428.64784606232;2466.371075375777;2214.5792299463133;2232.810211132056;2389.1996181279205;2460.372604985726;2477.2815883393882;1995.5011247188204;2309.7690230976937;2311.595470792613;2317.8145748340094;2318.2929206592066;2391.6532102845076;2445.76765207305;2448.069733501276;2448.791852692523;2449.191766716979;2153.6446486329737;2383.4041489627593;2464.4208610976407;2465.1125594785863;2483.7392073832348;8959.01450840407;9022.872140982377;9044.728658140266;9048.276137908952;9051.321150913642;5328.307320048369;5474.890502189974;5480.588955828329;5495.368032676253;5514.448555144453;3100.7829451938373;3489.2672538766888;3545.3441113394106;3626.2203626220225;3651.8904432867057;996.3772642098688;1042.7601651620093;1042.8696412948382;1046.6807623674777;1054.2093429927554;10180.23648226386;12040.939795301056;12044.819327710178;12045;12051.15641905067;4822.68652537584;4884.902301954012;4966.205407134841;4966.379202599675;5012.874678133003;10281.023302786223;10290.485475726184;10294.588216471257;10314.226433017553;10322.967703229617;5538.972305138448;5543.6396634218645;5545.500904918496;5552.361478430033;5557.805476808292;4514.503404625489;4944.9368385797225;4954.529319714621;5016.724080175552;5065.265536497187;3429.868143512635;3802.1825307559006;4019.0153412414147;4056.6551843093075;4128.401381799622;1336.2650542201945;1357.0907491980397;1358.9728205436033;1368.0765483298733;1387.6600232942792;800.3277247112337;859.9398042137116;860.6901515454431;909.3634455880909;975.3855071305032;5597.692126932976;5687.544996400361;5878;5939.07944268639;5963.880722385528;892.9909202601019;912.2428384440898;937.9437233765996;952.9285303602257;976.345848281653;3841.4429907663234;3935.2129574085297;3996.540397854226;3999.300122478555;4008.378701301303;10432.217583681255;10971.506282217319;11100.111991040698;11312.151588630886;11315.094792416587;9664.371815831948;10980.670579882617;11059.391733454577;11184.496697648625;11213.775724485464;8375.8324833503;8433.620487078097;8472.661093556311;8533.53065581399;8582.828343433097;5740;5866.911996319973;5884.146798714077;5902.763889444374;5947.583669143291;9844;9901.15840153595;10239.7440063999;10244.180465562587;10352.844707329245;10462.476876156314;10537.841932370868;10541.631042913461;10555.577776888838;10563.419011954109;6979.476539259677;7019.824504387431;7112.359887609933;7241.782746517613;7250.456215783732;8302.16978302189;8557.957210213908;8561.14388561139;8577.571121443778;8609.397342186237;7267.30960558746;7459.40324774006;7460.776176714708;7587.772366829004;7624.656890439833;7372.705091796268;7486.850262994819;7505.024346834876;7565;7642.312191902755;7046.436285097282;7048.8590228194125;7128.501004929605;7130.750423735144;7141.071660684078;4795.712257264575;4889.071076495455;4952.257161425815;5003.34956455673;5125.692458452505;4952.727599981891;5004.724740139327;5056.696598204119;5100.081985242692;5294.788208471618 01:54:49 INFO - PROCESS | 1993 | |4;prototype.html;303.6811348084512;308.8193406856988;320.967903209679;324.8278412441404;326.45156137688673;4484.080763443494;4706.458757242918;4862.759996200968;5050.267711181877;5271.3147290852185;4439.445069366329;5027.737518116853;5217.982493413786;5277.551408130304;5284.260203571503;3758.812059397033;4045.7977101144825;4058.472398588194;4067.1052368478886;4071.063655359269;3706.2402207547525;3804.9429258561145;4088.468499095128;4089.4440974391005;4098.8360465581345;3529.535242874204;3583.7133029357587;4058.2289365020697;4069.9186016279805;4086.427900093989;3410.6077801052948;3683.5395575553057;4054.4121102440126;4084.2852500812237;4086.2849001424634;3536.2043540203504;3537.8584856605703;3554.8091389384585;3564.750467467278;3574.767640103385;4906.484819093979;4953.069228396398;5039.44566097729;5054.609982254872;5109.591232701375;4624.630029597624;4626.537346265347;4630.236011058171;4655.86032419028;4673.789679464398;5130.615203859688;5150.716710580917;5283.260343551906;5533.529649979716;5560.638558493685;3583;3828.717379677811;3841.673457756094;3845.4039623858066;3846.480640147126;5780;5863.5309175265875;5866.6186697864505;5873.706314684248;5879.382664820175;3540.5574303212097;3643.5809881863397;3663.7435379523176;3895.0846551060476;3911.3155197840265;3795.2409518096492;4062.9187416252103;4065.0853557949226;4086.8773936781945;4108.568600296986;3866.226754649025;4605.171069207575;4649.3026046093355;4685;4687.29690546414;5018.372703412074;5410.350757908997;5608.439156084359;5622.775088996476;5623.372924463698;1261.4954018392716;1286.633309506786;1336.3295880149813;1380.1167252958114;1400.6288333591606;842.928351090152;846.1496196322779;866.6360128745941;866.7659731872359;880.5905254056894;3722.40441529354;3890.840583476141;4098.4467096941535;4275.140313963374;4282.914341713148;4285.785710714452;4302.913087152729;4335.674824388184;4714.175019371596;4740.463851839249;3871.128995975883;3978.5677156223737;3984.9203015940097;4223.556526564728;4347.7173983690955;2080.583883223361;2341.637046257833;2388.7611238875975;2436.439618887666;2584.5834145074414;4258.787060646955;4332.155229730172;4679.467002413225;4817.710937343771;5072.746362681851;4346.543612920661;4391.978040109779;4488.910221795546;4786.880327991828;4988.55103040728;4113.3957756475;4478.507364190002;4788.539495453851;4803.927941080887;4989.154013015208;4026.288827248466;4426.44669416323;4567.223571992836;4573.467888257471;4769.0431932202255;4525;4550.88622784433;4570.925832429443;4594.655400844883;4740.407449068866;4060.939085913773;4561;4792.400949881265;5016.846125391107;5040.294358789699;3458.948115778215;3459.668027809362;3791.829367678516;3914.807707683706;3937.2322397132284;5506.732282481109;5670.546356291405;5683.204351390726;5704.857378565569;5776.682282474504;4572.6799124061;5447.918281225864;5486.588505861996;5555.805546805843;5563.884224834106;5147.819826306061;5189.713635126045;5407.648502847303;5546.944530554642;5560.221568980427;4629.351890735233;5357.973210134081;5380;5382.273393091882;5382.30030096085;4769.546893045155;5253.343332083489;5501.231656315277;5503.11950087984;5557.972210138923;4566.383538222298;5297.020051290524;5370.301860758076;5393.759435329975;5414.7021913795115 01:54:49 INFO - PROCESS | 1993 | |5;yui.html;599.4425184578344;652.8922727749922;683.521534925552;716.0583832260577;721.0842230367433;15008.549743507698;15215.315310811013;15454.304556294966;15465.76801347978;15478.4582539611;11804.468798904049;13860.861391386083;14280.571582852504;14287.999840011204;14386.856131438682;893.8703887936246;899.1997122560927;911.7492689510372;913.1462082952461;913.7304495173906;724.3734169942987;747.36100633958;877.0265005843509;903.8192361527688;906.2659246010719;970.1075010989871;971.9659811906585;975.3026585991015;976.1214906584061;977.1254727019314;755.2409828122702;969.0358093696747;969.3069455339431;971.8930917599093;972.1882228339346;694.1808665774385;716.187127610161;718.3211864787778;718.3642476408364;720.8053825467147;12067.396630168545;14436.989410741253;14867.851321486754;14909.776353354708;14918.403263869432;12121.878781212252;12144.696382590417;12194;12510.624681259576;12636.620901372973;10684.145268378512;10719.142468602493;11086.722831929186;11160.609378671708;11411.315321080761;4003.399490076512;4118.19695159444;4129.339305711102;4138.8344466221315;4247.617714405687;72197;75528.62235688786;75716;79373;81065.59467202685;9350;9448.716538503855;9505.42967421957;9597.040295970486;10106.494675266207;20905.477363066046;20923.895380523;20946.95265236732;20968.266110686054;20998;7063.081799366049;7133.964330178316;7198.892016619755;7450.105987281561;7775.844483110419;3878.146807702301;3906.980465097656;3952.8023598819946;3953.2291203215673;3964.246793109358;3402.863885444604;3505.5618047744033;3516.5955915069585;3523.6828685418436;3526.7531272810656;631.5263552335748;665.2017578905333;676.1615596660106;698.4447364345358;706.0080586775589;548.7844424599531;591.4765432592101;597.2713289786453;608.0787606775739;635.7726073113861;8054.436189466798;8203.261706446328;8323.791905202419;8333.125021872738;8394.622241999125;584.3221862639318;585.9765609375662;592.1088761414044;606.9089636554464;609.5641616244407;3142.4972004479173;3907.0623050468253;3914.9021274467796;3949.624785645359;3988.8603898863403;96442.51778741062;99067.50466247623;99396.00603994013;100878.47803804964;100954;67891.30271743245;85740.14259857322;86375.56812216024;86600;87155.8211044729;67901.98147027798;68556.6572167136;68812.65593672;72453.637731811;72972;8068.112507624274;8072.9192708074515;8266.090730019572;8326.750197494084;8329.250367466957;42447.28689917009;43718.78140609276;44062.77968610136;44597.66207013795;46354.76822615865;50842;62183;62649.68675156595;63928;64463.67768161317;10893.291936024134;11068.557257709923;11101.944490277498;11246.493907774333;11399.943000284946;11992.700182495508;12140.210886292363;12382.5666101686;12470.750584988431;12920.935395323339;11526.366049867338;11671.241369310968;11696.824547631622;11782.293062416282;11876;11225.270357426742;11306.94346528262;11309.547618095185;11663;11718.531258749554;11154.386508742096;11211.383373914186;11217.102631789274;11590.95681388679;11710.063194944214;7847.803804904923;9424.387414818015;9588.232941364535;9676.27427942893;9768.120869121813;8659.350548708999;9205.86191207119;9229.307801914749;9600.231981441328;9631.566579504079 01:54:49 INFO - PROCESS | 1993 | __end_tp_report 01:54:49 INFO - PROCESS | 1993 | __start_cc_report 01:54:49 INFO - PROCESS | 1993 | _x_x_mozilla_cycle_collect,1440 01:54:49 INFO - PROCESS | 1993 | __end_cc_report 01:54:49 INFO - PROCESS | 1993 | __startTimestamp1463129689763__endTimestamp 01:54:49 INFO - PROCESS | 1993 | 01:54:49 INFO - PROCESS | 1993 | ------- Summary: start ------- 01:54:49 INFO - PROCESS | 1993 | Number of tests: 6 01:54:49 INFO - PROCESS | 1993 | 01:54:49 INFO - PROCESS | 1993 | [#0] dojo.html Cycles:165 Average:5706.42 Median:5953.73 stddev:641.50 (10.8%) stddev-sans-first:617.18 01:54:49 INFO - PROCESS | 1993 | Values: 3389.3 3392.8 3414.4 3430.9 3460.9 5656.4 5672.3 5701.4 5799.0 5830.5 5755.1 5757.2 5790.9 5833.2 5863.4 5093.5 5145.3 5194.3 5215.3 5295.5 5266.4 5313.8 5318.8 5320.9 5356.7 5098.4 5168.3 5174.8 5222.0 5223.5 5210.8 5219.8 5223.7 5230.5 5233.3 5080.8 5102.7 5177.6 5183.2 5202.2 4999.6 5567.6 5592.9 5626.9 5657.9 6103.2 6146.0 6149.7 6169.7 6177.5 6043.3 6105.3 6123.5 6199.4 6209.6 4857.2 5826.3 5846.7 5848.0 5855.2 6209.8 6232.9 6241.3 6246.8 6251.3 5467.2 5949.0 6002.4 6139.7 6146.5 6141.0 6171.3 6173.3 6227.8 6243.1 6179.3 6189.3 6217.8 6220.0 6234.4 6101.2 6139.3 6153.8 6223.3 6225.8 4851.1 4878.6 4888.8 4900.1 4902.8 3453.8 4379.8 4380.9 4393.9 4428.8 5787.9 5795.4 5809.1 5815.1 5819.9 6136.9 6147.9 6165.5 6193.5 6214.3 5754.4 5762.9 5764.0 5773.1 5801.9 5250.0 5315.1 5378.1 5401.2 5402.9 6186.8 6217.2 6239.4 6278.3 6344.8 6179.6 6181.4 6186.1 6204.8 6227.0 5229.5 6288.7 6301.5 6316.0 6327.3 6056.8 6106.5 6115.6 6161.2 6179.4 6170.6 6193.0 6213.3 6213.4 6237.9 5933.5 5984.5 5996.0 6018.9 6061.6 6034.9 6114.8 6120.4 6123.0 6141.5 5999.7 6002.2 6008.6 6038.0 6047.5 5889.5 5953.7 6000.8 6028.0 6108.6 5916.6 5951.5 5968.1 5968.6 5993.0 01:54:49 INFO - PROCESS | 1993 | 01:54:49 INFO - PROCESS | 1993 | [#1] ext.html Cycles:180 Average:62713.11 Median:19893.11 stddev:130658.38 (656.8%) stddev-sans-first:130941.40 01:54:49 INFO - PROCESS | 1993 | Values: 494.1 498.1 501.8 513.9 539.8 30637.5 30937.5 32617.3 41084.4 42397.8 43363.6 43822.7 43983.8 44027.3 44234.8 9701.3 9874.8 9896.4 9900.8 9905.2 8680.9 8839.6 8867.4 8898.5 9575.4 8713.8 11767.2 11962.4 12010.8 12046.9 11854.6 11954.7 11964.2 11991.8 12010.0 8581.5 11643.1 11725.2 11829.2 11830.6 38417.2 40621.0 40625.8 40651.0 40725.6 3257.3 3397.5 3438.2 3550.4 3610.1 28372.9 28392.7 28975.0 29258.1 29260.9 2718.3 2725.5 2730.2 2765.8 2830.0 377947.0 378638.0 378749.1 379166.0 379695.0 51601.7 58628.4 58835.1 60231.4 60416.0 62646.7 62668.7 62921.0 62946.7 63049.7 15377.3 15565.5 15571.7 15578.0 15585.1 12151.3 12236.8 12259.3 12263.1 12423.2 14042.6 14399.2 15415.3 15599.0 15657.0 6330.9 6460.3 6498.8 6508.3 6515.2 919.6 959.5 1022.1 1038.1 1135.2 25783.7 28883.6 29282.6 29671.6 29702.7 1035.3 1078.6 1102.6 1172.3 1184.5 14938.7 15013.0 15076.1 15322.6 15441.4 563017.0 566526.0 567556.0 568489.0 568639.0 56608.0 57888.0 57966.0 57985.0 58098.7 1467.4 1480.4 1485.3 1500.9 1507.0 22930.1 28231.4 28801.7 29289.9 29301.7 49408.0 49854.0 50031.0 50040.0 50142.2 504538.0 506978.0 507091.0 508729.0 508823.0 29082.7 29555.3 38679.8 38959.4 39663.0 34295.7 38268.8 45143.5 47720.8 49162.0 36907.4 37013.0 37294.1 37701.4 38084.2 35945.6 36176.1 36304.8 36935.6 37183.0 35402.9 35906.3 36088.5 36660.5 36701.5 24749.8 30165.8 32547.5 32695.8 33370.3 24318.4 24504.4 24746.5 24787.4 24888.6 01:54:49 INFO - PROCESS | 1993 | 01:54:49 INFO - PROCESS | 1993 | [#2] jquery.html Cycles:180 Average:36203.41 Median:22711.49 stddev:98673.93 (434.5%) stddev-sans-first:98914.09 01:54:49 INFO - PROCESS | 1993 | Values: 390.5 395.3 398.6 403.2 422.9 14327.9 20028.5 20648.7 21091.4 21128.4 15947.8 17637.4 20344.0 20601.9 20710.0 5596.7 5864.0 5876.1 5886.2 5887.0 5668.5 5671.3 5747.1 5749.1 5756.1 4000.4 4218.9 4220.3 4230.6 4234.2 4219.6 4232.8 5957.7 6305.1 6314.1 5250.7 5530.9 5541.8 5545.6 5554.8 22608.2 22814.8 25555.9 25941.5 25981.1 2152.8 2156.0 2159.0 2321.8 2796.3 14595.8 14778.7 14798.3 15071.0 15279.8 1764.6 1772.6 1815.4 1849.3 1872.3 136862.0 145350.0 149656.0 150542.9 156362.2 20820.4 20937.6 21058.9 21203.4 21287.9 27894.2 34585.5 34719.8 34811.8 34870.0 10620.4 10829.8 10834.5 10864.5 10978.0 8801.2 8801.7 8857.7 8937.6 8947.7 8025.5 9321.5 9645.4 9724.3 9740.7 3703.1 3798.8 3810.9 3884.0 3898.6 891.7 914.1 923.3 960.0 1029.4 18788.8 19699.8 19753.0 19755.6 19901.9 925.8 939.9 970.0 1006.4 1028.2 10557.4 10567.4 10571.8 10587.7 10589.5 569419.0 573207.0 586184.0 601457.0 601695.0 122160.8 127815.4 133561.0 133868.3 135045.0 1008.6 1018.0 1060.3 1087.9 1088.5 14523.9 16889.2 17529.0 17776.8 17785.0 38175.6 49497.8 50039.2 50504.0 50661.7 59445.0 74150.0 75832.6 76745.0 77986.0 8229.7 8233.3 8279.8 8301.9 8610.1 11802.3 12210.0 12390.5 12514.6 12537.7 10573.9 10927.2 10989.1 11102.5 11180.8 10431.2 10482.8 10648.1 10732.2 10907.7 10955.5 11073.9 11080.4 11358.8 11376.0 7199.4 8025.9 8228.9 8255.0 8256.0 8369.6 10243.9 10798.1 11035.6 11083.5 01:54:49 INFO - PROCESS | 1993 | 01:54:49 INFO - PROCESS | 1993 | [#3] mootools.html Cycles:180 Average:5804.74 Median:4966.29 stddev:3280.75 (66.1%) stddev-sans-first:3273.52 01:54:49 INFO - PROCESS | 1993 | Values: 1435.1 1583.1 1620.0 1856.6 1994.9 8015.0 8769.0 9014.3 9049.5 9150.6 8778.8 8985.9 9002.5 9009.8 9013.4 2113.2 2197.8 2198.6 2428.6 2466.4 2214.6 2232.8 2389.2 2460.4 2477.3 1995.5 2309.8 2311.6 2317.8 2318.3 2391.7 2445.8 2448.1 2448.8 2449.2 2153.6 2383.4 2464.4 2465.1 2483.7 8959.0 9022.9 9044.7 9048.3 9051.3 5328.3 5474.9 5480.6 5495.4 5514.4 3100.8 3489.3 3545.3 3626.2 3651.9 996.4 1042.8 1042.9 1046.7 1054.2 10180.2 12040.9 12044.8 12045.0 12051.2 4822.7 4884.9 4966.2 4966.4 5012.9 10281.0 10290.5 10294.6 10314.2 10323.0 5539.0 5543.6 5545.5 5552.4 5557.8 4514.5 4944.9 4954.5 5016.7 5065.3 3429.9 3802.2 4019.0 4056.7 4128.4 1336.3 1357.1 1359.0 1368.1 1387.7 800.3 859.9 860.7 909.4 975.4 5597.7 5687.5 5878.0 5939.1 5963.9 893.0 912.2 937.9 952.9 976.3 3841.4 3935.2 3996.5 3999.3 4008.4 10432.2 10971.5 11100.1 11312.2 11315.1 9664.4 10980.7 11059.4 11184.5 11213.8 8375.8 8433.6 8472.7 8533.5 8582.8 5740.0 5866.9 5884.1 5902.8 5947.6 9844.0 9901.2 10239.7 10244.2 10352.8 10462.5 10537.8 10541.6 10555.6 10563.4 6979.5 7019.8 7112.4 7241.8 7250.5 8302.2 8558.0 8561.1 8577.6 8609.4 7267.3 7459.4 7460.8 7587.8 7624.7 7372.7 7486.9 7505.0 7565.0 7642.3 7046.4 7048.9 7128.5 7130.8 7141.1 4795.7 4889.1 4952.3 5003.3 5125.7 4952.7 5004.7 5056.7 5100.1 5294.8 01:54:49 INFO - PROCESS | 1993 | 01:54:49 INFO - PROCESS | 1993 | [#4] prototype.html Cycles:180 Average:4234.74 Median:4569.07 stddev:1270.83 (27.8%) stddev-sans-first:1239.67 01:54:49 INFO - PROCESS | 1993 | Values: 303.7 308.8 321.0 324.8 326.5 4484.1 4706.5 4862.8 5050.3 5271.3 4439.4 5027.7 5218.0 5277.6 5284.3 3758.8 4045.8 4058.5 4067.1 4071.1 3706.2 3804.9 4088.5 4089.4 4098.8 3529.5 3583.7 4058.2 4069.9 4086.4 3410.6 3683.5 4054.4 4084.3 4086.3 3536.2 3537.9 3554.8 3564.8 3574.8 4906.5 4953.1 5039.4 5054.6 5109.6 4624.6 4626.5 4630.2 4655.9 4673.8 5130.6 5150.7 5283.3 5533.5 5560.6 3583.0 3828.7 3841.7 3845.4 3846.5 5780.0 5863.5 5866.6 5873.7 5879.4 3540.6 3643.6 3663.7 3895.1 3911.3 3795.2 4062.9 4065.1 4086.9 4108.6 3866.2 4605.2 4649.3 4685.0 4687.3 5018.4 5410.4 5608.4 5622.8 5623.4 1261.5 1286.6 1336.3 1380.1 1400.6 842.9 846.1 866.6 866.8 880.6 3722.4 3890.8 4098.4 4275.1 4282.9 4285.8 4302.9 4335.7 4714.2 4740.5 3871.1 3978.6 3984.9 4223.6 4347.7 2080.6 2341.6 2388.8 2436.4 2584.6 4258.8 4332.2 4679.5 4817.7 5072.7 4346.5 4392.0 4488.9 4786.9 4988.6 4113.4 4478.5 4788.5 4803.9 4989.2 4026.3 4426.4 4567.2 4573.5 4769.0 4525.0 4550.9 4570.9 4594.7 4740.4 4060.9 4561.0 4792.4 5016.8 5040.3 3458.9 3459.7 3791.8 3914.8 3937.2 5506.7 5670.5 5683.2 5704.9 5776.7 4572.7 5447.9 5486.6 5555.8 5563.9 5147.8 5189.7 5407.6 5546.9 5560.2 4629.4 5358.0 5380.0 5382.3 5382.3 4769.5 5253.3 5501.2 5503.1 5558.0 4566.4 5297.0 5370.3 5393.8 5414.7 01:54:49 INFO - PROCESS | 1993 | 01:54:49 INFO - PROCESS | 1993 | [#5] yui.html Cycles:180 Average:18162.45 Median:603.18 stddev:25862.34 (4287.7%) stddev-sans-first:25901.27 01:54:49 INFO - PROCESS | 1993 | Values: 599.4 652.9 683.5 716.1 721.1 15008.5 15215.3 15454.3 15465.8 15478.5 11804.5 13860.9 14280.6 14288.0 14386.9 893.9 899.2 911.7 913.1 913.7 724.4 747.4 877.0 903.8 906.3 970.1 972.0 975.3 976.1 977.1 755.2 969.0 969.3 971.9 972.2 694.2 716.2 718.3 718.4 720.8 12067.4 14437.0 14867.9 14909.8 14918.4 12121.9 12144.7 12194.0 12510.6 12636.6 10684.1 10719.1 11086.7 11160.6 11411.3 4003.4 4118.2 4129.3 4138.8 4247.6 72197.0 75528.6 75716.0 79373.0 81065.6 9350.0 9448.7 9505.4 9597.0 10106.5 20905.5 20923.9 20947.0 20968.3 20998.0 7063.1 7134.0 7198.9 7450.1 7775.8 3878.1 3907.0 3952.8 3953.2 3964.2 3402.9 3505.6 3516.6 3523.7 3526.8 631.5 665.2 676.2 698.4 706.0 548.8 591.5 597.3 608.1 635.8 8054.4 8203.3 8323.8 8333.1 8394.6 584.3 586.0 592.1 606.9 609.6 3142.5 3907.1 3914.9 3949.6 3988.9 96442.5 99067.5 99396.0 100878.5 100954.0 67891.3 85740.1 86375.6 86600.0 87155.8 67902.0 68556.7 68812.7 72453.6 72972.0 8068.1 8072.9 8266.1 8326.8 8329.3 42447.3 43718.8 44062.8 44597.7 46354.8 50842.0 62183.0 62649.7 63928.0 64463.7 10893.3 11068.6 11101.9 11246.5 11399.9 11992.7 12140.2 12382.6 12470.8 12920.9 11526.4 11671.2 11696.8 11782.3 11876.0 11225.3 11306.9 11309.5 11663.0 11718.5 11154.4 11211.4 11217.1 11591.0 11710.1 7847.8 9424.4 9588.2 9676.3 9768.1 8659.4 9205.9 9229.3 9600.2 9631.6 01:54:49 INFO - PROCESS | 1993 | -------- Summary: end -------- 01:54:49 INFO - PROCESS | 1993 | 01:54:50 INFO - TEST-INFO | 1993: exit 0 01:54:50 INFO - TEST-OK | dromaeo_css | took 1115722ms 01:54:50 INFO - TEST-START | kraken 01:54:50 INFO - operating with platform_type : mac_ 01:54:50 INFO - Initialising browser for kraken test... 01:54:50 INFO - TEST-INFO | started process 1995 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/tmpAZbGS9/profile http://localhost:49385/getInfo.html) 01:54:52 INFO - PROCESS | 1995 | __metrics Screen width/height:1600/1200 01:54:52 INFO - PROCESS | 1995 | colorDepth:24 01:54:52 INFO - PROCESS | 1995 | Browser inner width/height: 1024/689 01:54:52 INFO - PROCESS | 1995 | __metrics 01:54:52 INFO - PROCESS | 1995 | 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" 01:54:52 ERROR - PROCESS | 1995 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:54:53 INFO - TEST-INFO | 1995: exit 0 01:54:53 INFO - Browser initialized. 01:54:53 INFO - Running cycle 1/1 for kraken test... 01:54:53 INFO - TEST-INFO | started process 1997 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/tmpAZbGS9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:55:05 INFO - PROCESS | 1997 | RSS: Main: 238948352 01:55:05 INFO - PROCESS | 1997 | 01:55:07 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49385/tests/kraken/driver.html?testName=audio-beat-detection) 01:55:08 INFO - PROCESS | 1997 | RSS: Main: 287895552 01:55:08 INFO - PROCESS | 1997 | 01:55:11 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49385/tests/kraken/driver.html?testName=audio-dft) 01:55:12 INFO - PROCESS | 1997 | RSS: Main: 297627648 01:55:12 INFO - PROCESS | 1997 | 01:55:15 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49385/tests/kraken/driver.html?testName=audio-fft) 01:55:16 INFO - PROCESS | 1997 | RSS: Main: 384671744 01:55:16 INFO - PROCESS | 1997 | 01:55:18 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49385/tests/kraken/driver.html?testName=audio-oscillator) 01:55:19 INFO - PROCESS | 1997 | RSS: Main: 286429184 01:55:19 INFO - PROCESS | 1997 | 01:55:21 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49385/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:55:22 INFO - PROCESS | 1997 | RSS: Main: 289964032 01:55:22 INFO - PROCESS | 1997 | 01:55:25 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49385/tests/kraken/driver.html?testName=imaging-darkroom) 01:55:25 INFO - PROCESS | 1997 | RSS: Main: 328982528 01:55:25 INFO - PROCESS | 1997 | 01:55:28 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49385/tests/kraken/driver.html?testName=imaging-desaturate) 01:55:29 INFO - PROCESS | 1997 | RSS: Main: 329535488 01:55:29 INFO - PROCESS | 1997 | 01:55:32 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49385/tests/kraken/driver.html?testName=json-parse-financial) 01:55:33 INFO - PROCESS | 1997 | RSS: Main: 328712192 01:55:33 INFO - PROCESS | 1997 | 01:55:34 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49385/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:55:35 INFO - PROCESS | 1997 | RSS: Main: 316620800 01:55:35 INFO - PROCESS | 1997 | 01:55:37 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49385/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:55:38 INFO - PROCESS | 1997 | RSS: Main: 323346432 01:55:38 INFO - PROCESS | 1997 | 01:55:40 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49385/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:55:41 INFO - PROCESS | 1997 | RSS: Main: 325578752 01:55:41 INFO - PROCESS | 1997 | 01:55:44 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49385/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:55:44 INFO - PROCESS | 1997 | RSS: Main: 329826304 01:55:44 INFO - PROCESS | 1997 | 01:55:47 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49385/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:55:48 INFO - PROCESS | 1997 | RSS: Main: 327553024 01:55:48 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | Cycle 1(1): loaded http://localhost:49385/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49385/tests/kraken/driver.html?testName=ai-astar) 01:55:50 INFO - PROCESS | 1997 | RSS: Main: 374136832 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | __start_tp_report 01:55:50 INFO - PROCESS | 1997 | _x_x_mozilla_page_load 01:55:50 INFO - PROCESS | 1997 | _x_x_mozilla_page_load_details 01:55:50 INFO - PROCESS | 1997 | |i|pagename|runs| 01:55:50 INFO - PROCESS | 1997 | |0;ai-astar;113;107;113;112;112;113;112;114;113;111 01:55:50 INFO - PROCESS | 1997 | |1;audio-beat-detection;154;108;141;199;121;136;136;117;167;118 01:55:50 INFO - PROCESS | 1997 | |2;audio-dft;187;186;185;187;184;186;184;184;186;179 01:55:50 INFO - PROCESS | 1997 | |3;audio-fft;79;75;58;126;59;83;79;82;126;56 01:55:50 INFO - PROCESS | 1997 | |4;audio-oscillator;94;96;145;110;100;77;102;80;80;102 01:55:50 INFO - PROCESS | 1997 | |5;imaging-gaussian-blur;93;86;90;87;90;86;90;96;130;128 01:55:50 INFO - PROCESS | 1997 | |6;imaging-darkroom;95;96;95;95;95;95;96;94;94;125 01:55:50 INFO - PROCESS | 1997 | |7;imaging-desaturate;87;84;85;85;84;84;85;84;86;84 01:55:50 INFO - PROCESS | 1997 | |8;json-parse-financial;45;47;81;49;52;58;79;168;76;57 01:55:50 INFO - PROCESS | 1997 | |9;json-stringify-tinderbox;63;62;79;72;92;95;65;69;67;67 01:55:50 INFO - PROCESS | 1997 | |10;stanford-crypto-aes;85;84;87;85;81;84;81;84;85;85 01:55:50 INFO - PROCESS | 1997 | |11;stanford-crypto-ccm;140;140;134;138;137;135;135;160;246;156 01:55:50 INFO - PROCESS | 1997 | |12;stanford-crypto-pbkdf2;167;187;175;168;167;167;214;187;166;166 01:55:50 INFO - PROCESS | 1997 | |13;stanford-crypto-sha256-iterative;67;66;68;69;66;65;65;67;67;65 01:55:50 INFO - PROCESS | 1997 | __end_tp_report 01:55:50 INFO - PROCESS | 1997 | __start_cc_report 01:55:50 INFO - PROCESS | 1997 | _x_x_mozilla_cycle_collect,2284 01:55:50 INFO - PROCESS | 1997 | __end_cc_report 01:55:50 INFO - PROCESS | 1997 | __startTimestamp1463129750447__endTimestamp 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | ------- Summary: start ------- 01:55:50 INFO - PROCESS | 1997 | Number of tests: 14 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:1.94 (1.7%) stddev-sans-first:2.03 01:55:50 INFO - PROCESS | 1997 | Values: 113.0 107.0 113.0 112.0 112.0 113.0 112.0 114.0 113.0 111.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:138.50 stddev:27.56 (19.9%) stddev-sans-first:28.74 01:55:50 INFO - PROCESS | 1997 | Values: 154.0 108.0 141.0 199.0 121.0 136.0 136.0 117.0 167.0 118.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#2] audio-dft Cycles:10 Average:184.80 Median:186.00 stddev:2.35 (1.3%) stddev-sans-first:2.35 01:55:50 INFO - PROCESS | 1997 | Values: 187.0 186.0 185.0 187.0 184.0 186.0 184.0 184.0 186.0 179.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#3] audio-fft Cycles:10 Average:82.30 Median:77.00 stddev:25.21 (32.7%) stddev-sans-first:26.71 01:55:50 INFO - PROCESS | 1997 | Values: 79.0 75.0 58.0 126.0 59.0 83.0 79.0 82.0 126.0 56.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:78.50 stddev:19.70 (25.1%) stddev-sans-first:20.83 01:55:50 INFO - PROCESS | 1997 | Values: 94.0 96.0 145.0 110.0 100.0 77.0 102.0 80.0 80.0 102.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#5] imaging-gaussian-blur Cycles:10 Average:97.60 Median:90.00 stddev:16.84 (18.7%) stddev-sans-first:17.78 01:55:50 INFO - PROCESS | 1997 | Values: 93.0 86.0 90.0 87.0 90.0 86.0 90.0 96.0 130.0 128.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:95.00 stddev:9.51 (10.0%) stddev-sans-first:10.02 01:55:50 INFO - PROCESS | 1997 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 94.0 94.0 125.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.03 (1.2%) stddev-sans-first:0.73 01:55:50 INFO - PROCESS | 1997 | Values: 87.0 84.0 85.0 85.0 84.0 84.0 85.0 84.0 86.0 84.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#8] json-parse-financial Cycles:10 Average:71.20 Median:57.50 stddev:36.61 (63.7%) stddev-sans-first:37.58 01:55:50 INFO - PROCESS | 1997 | Values: 45.0 47.0 81.0 49.0 52.0 58.0 79.0 168.0 76.0 57.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:70.50 stddev:11.81 (16.7%) stddev-sans-first:11.95 01:55:50 INFO - PROCESS | 1997 | Values: 63.0 62.0 79.0 72.0 92.0 95.0 65.0 69.0 67.0 67.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.00 stddev:1.85 (2.2%) stddev-sans-first:1.94 01:55:50 INFO - PROCESS | 1997 | Values: 85.0 84.0 87.0 85.0 81.0 84.0 81.0 84.0 85.0 85.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:140.00 stddev:34.20 (24.4%) stddev-sans-first:35.99 01:55:50 INFO - PROCESS | 1997 | Values: 140.0 140.0 134.0 138.0 137.0 135.0 135.0 160.0 246.0 156.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:171.50 stddev:15.61 (9.1%) stddev-sans-first:16.18 01:55:50 INFO - PROCESS | 1997 | Values: 167.0 187.0 175.0 168.0 167.0 167.0 214.0 187.0 166.0 166.0 01:55:50 INFO - PROCESS | 1997 | 01:55:50 INFO - PROCESS | 1997 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.35 (2.0%) stddev-sans-first:1.42 01:55:50 INFO - PROCESS | 1997 | Values: 67.0 66.0 68.0 69.0 66.0 65.0 65.0 67.0 67.0 65.0 01:55:50 INFO - PROCESS | 1997 | -------- Summary: end -------- 01:55:50 INFO - PROCESS | 1997 | 01:55:51 INFO - TEST-INFO | 1997: exit 0 01:55:51 INFO - TEST-OK | kraken | took 60985ms 01:55:51 INFO - SUITE-END | took 1176s 01:55:51 INFO - Completed test suite (00:19:37) 01:55:51 INFO - javascript benchmark 01:55:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3389.2713066690662, 3392.762506624536, 3414.385410626087, 3430.8627654893808, 3460.9307813843725, 5656.377798442168, 5672.347680016799, 5701.401352857947, 5798.971005144974, 5830.475257226849, 5755.0504166812425, 5757.1652110444065, 5790.884182316351, 5833.154192642064, 5863.442972917566, 5093.465186155456, 5145.33110695611, 5194.298769666103, 5215.348081489814, 5295.523402893738, 5266.394364648077, 5313.760880760375, 5318.787248510055, 5320.9467905321035, 5356.732163391835, 5098.362704661918, 5168.302279192299, 5174.844754657366, 5221.973890130544, 5223.451537588575, 5210.76551555181, 5219.817306394296, 5223.660462069991, 5230.450802665741, 5233.267342572043, 5080.755214972355, 5102.719350435725, 5177.611679124043, 5183.196604526304, 5202.167653175474, 4999.625028122869, 5567.582431317667, 5592.86017849553, 5626.9437305626825, 5657.9434205658235, 6103.169049285197, 6145.955187618071, 6149.723262453201, 6169.691515424256, 6177.536684748662, 6043.2748070231855, 6105.32841387447, 6123.510119190499, 6199.411055949678, 6209.565330426872, 4857.247126695333, 5826.329972053268, 5846.736896839652, 5847.970760146257, 5855.180274761537, 6209.844753881099, 6232.937670623236, 6241.34465881085, 6246.781362652286, 6251.343608921089, 5467.152591348347, 5948.970255148696, 6002.369751176064, 6139.693015349215, 6146.4775494082605, 6140.964409184515, 6171.259448866164, 6173.32093469718, 6227.813165605039, 6243.094751261042, 6179.28938172107, 6189.288231853305, 6217.751289948352, 6219.968900155471, 6234.438900498977, 6101.206843110367, 6139.277491147187, 6153.753849846045, 6223.346548612421, 6225.8132256032395, 4851.102546028996, 4878.609711223094, 4888.828890988798, 4900.068986892479, 4902.803887844446, 3453.792772433662, 4379.802908869108, 4380.890477738082, 4393.912121757547, 4428.778561071934, 5787.8553036174435, 5795.362510123883, 5809.0705487122705, 5815.069588865846, 5819.94180058194, 6136.8772624547255, 6147.877042459126, 6165.537584681167, 6193.473554747897, 6214.316425193225, 5754.367019627837, 5762.88474230513, 5763.991301522216, 5773.076307790733, 5801.94198058014, 5249.973750131225, 5315.149576067811, 5378.139497680352, 5401.189821526802, 5402.945970540244, 6186.752529898754, 6217.160683307696, 6239.407256310734, 6278.277998030102, 6344.841378965562, 6179.598326108789, 6181.350958149509, 6186.133941248138, 6204.751809927552, 6227.0, 5229.450907654642, 6288.654124023223, 6301.527385446018, 6315.96842015787, 6327.272363678236, 6056.818295451144, 6106.5114790817515, 6115.57501430398, 6161.199044124235, 6179.412955769195, 6170.629762214281, 6193.040078787795, 6213.254409470893, 6213.409726076015, 6237.875242495215, 5933.525317974638, 5984.461398474158, 5996.019199173934, 6018.879622407615, 6061.636301821904, 6034.9094763577705, 6114.753609057623, 6120.355709576858, 6123.038605230386, 6141.455323060992, 5999.670018149044, 6002.249718785152, 6008.579399441997, 6038.0, 6047.485963693135, 5889.528837692888, 5953.732082056231, 6000.8499787503915, 6028.0, 6108.633481991094, 5916.615419997686, 5951.464368206882, 5968.074948383008, 5968.552358573212, 5993.0], "value": 5665.792330038523, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.0884068892893, 498.1133582223643, 501.8318863180832, 513.9383274007118, 539.7948779463803, 30637.540436893465, 30937.535936960936, 32617.347653046923, 41084.38373424401, 42397.7880110599, 43363.566364336344, 43822.68531944047, 43983.78008109955, 44027.33958990618, 44234.77882610582, 9701.27240456965, 9874.753131171707, 9896.356736812084, 9900.801983960317, 9905.15806156474, 8680.8697869532, 8839.646414143426, 8867.379283450162, 8898.466092034467, 9575.377600455948, 8713.823633809418, 11767.176297659167, 11962.401879906014, 12010.759784804342, 12046.879531204708, 11854.64436066915, 11954.701132471715, 11964.222325548812, 11991.800819918026, 12010.03919686423, 8581.485110893367, 11643.126765492538, 11725.179237453382, 11829.231099978473, 11830.585929492427, 38417.23165536673, 40620.98447538805, 40625.79687101545, 40651.0, 40725.59274407248, 3257.2833976525126, 3397.541331920184, 3438.157651375429, 3550.414181660023, 3610.1335679436747, 28372.86508539656, 28392.716072839215, 28975.0, 29258.122256332343, 29260.8536957316, 2718.3340081679917, 2725.4957832800796, 2730.180945716297, 2765.764909982654, 2829.9717002829916, 377946.99999999726, 378638.0, 378749.1062544697, 379166.0, 379695.0, 51601.74199129055, 58628.36172372253, 58835.11747323794, 60231.39768602258, 60416.0, 62646.68676656679, 62668.686656566424, 62921.0, 62946.685266573375, 63049.68475157687, 15377.308021139075, 15565.455209067855, 15571.688566228839, 15577.98743081696, 15585.142817144939, 12151.331676757687, 12236.81644775329, 12259.264444133381, 12263.141580089487, 12423.192492487959, 14042.578722638336, 14399.208043557495, 15415.306311216022, 15599.0, 15657.0, 6330.905036424458, 6460.289368169498, 6498.837529061811, 6508.251551071593, 6515.2181738190775, 919.595378033663, 959.4627008875052, 1022.1107636356269, 1038.1383451735041, 1135.1543100390097, 25783.710814459202, 28883.56674649882, 29282.648559251542, 29671.554926676115, 29702.702972969993, 1035.321864178964, 1078.6170909327234, 1102.5755084292605, 1172.3200543684643, 1184.5498710489958, 14938.70122597542, 15013.024153429991, 15076.095434273759, 15322.616934576501, 15441.382344706311, 563017.0, 566526.0, 567556.0, 568489.0, 568639.0, 56608.0, 57888.0, 57966.0, 57985.0, 58098.7095064522, 1467.4057006912217, 1480.4448331875547, 1485.2870622101668, 1500.932458039369, 1507.0279669613037, 22930.082796687944, 28231.43537129287, 28801.711982879904, 29289.85355073296, 29301.70698293075, 49408.0, 49854.0, 50031.0, 50040.0, 50142.24786628131, 504538.0, 506978.0, 507091.0, 508729.0, 508823.0, 29082.709172908, 29555.261118472212, 38679.80660096682, 38959.415608765325, 39663.00842478846, 34295.657043429244, 38268.80865595654, 45143.548564513934, 47720.7613961928, 49162.0, 36907.44638830366, 37013.0, 37294.06764830901, 37701.434478483396, 38084.23831523409, 35945.64054359423, 36176.09559761027, 36304.81847590745, 36935.63064369322, 37183.0, 35402.93791186268, 35906.28187436289, 36088.45867312045, 36660.45009324916, 36701.53193872322, 24749.752502474745, 30165.849170754005, 32547.511787322735, 32695.836520817244, 33370.33259334848, 24318.392040199138, 24504.38739031467, 24746.50506989886, 24787.380315492257, 24888.626670599595], "value": 19512.481288955692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.45336528859593, 395.2569169960473, 398.5695448915172, 403.1815414708142, 422.9450171477707, 14327.928360358184, 20028.4992875178, 20648.690269645966, 21091.367258982234, 21128.366149015517, 15947.84052159478, 17637.382691605795, 20343.982800859936, 20601.896990515062, 20710.0, 5596.748146333414, 5864.0, 5876.118582212681, 5886.205362276102, 5886.970565147169, 5668.549529949521, 5671.291088613923, 5747.109198074284, 5749.108888122348, 5756.079027355624, 4000.359942409215, 4218.852340168095, 4220.324748040314, 4230.555791641879, 4234.21666991606, 4219.578042195786, 4232.76719780412, 5957.672328021957, 6305.117283580303, 6314.116023756677, 5250.658707184059, 5530.917036244419, 5541.833744987657, 5545.556355491551, 5554.805581804618, 22608.208712694977, 22814.771852281432, 25555.872220638965, 25941.481170376486, 25981.090661826933, 2152.763196048433, 2155.9784402155933, 2158.9568208635897, 2321.814254859607, 2796.2869468285458, 14595.78106328406, 14778.704425911528, 14798.260086995608, 15071.020383675026, 15279.770803437958, 1764.5676809181705, 1772.6277481728853, 1815.4190658989128, 1849.3249963763265, 1872.2885303584687, 136862.0, 145350.0, 149656.0, 150542.87789974397, 156362.21818890946, 20820.37538873836, 20937.581248374947, 21058.894705526374, 21203.36389908305, 21287.8935605321, 27894.16317510478, 34585.48121778175, 34719.82640086783, 34811.78158764431, 34870.0, 10620.362778233331, 10829.83755243672, 10834.512446939907, 10864.511097000654, 10978.0, 8801.207891289814, 8801.691940782042, 8857.689980850639, 8937.597808098652, 8947.686830960885, 8025.478343907744, 9321.533923303808, 9645.37305075168, 9724.319297649237, 9740.707778766648, 3703.0929071528185, 3798.8290526926353, 3810.8666196682984, 3883.9997401338333, 3898.641322000149, 891.7057371067532, 914.131575003749, 923.3075193604797, 959.9904000960041, 1029.4080903480528, 18788.779602178794, 19699.803001969798, 19753.0, 19755.60488790245, 19901.900490497454, 925.8472352061834, 939.9248060155307, 969.9612015519441, 1006.3861044762689, 1028.1620479309338, 10557.366558006544, 10567.418791966362, 10571.841422378671, 10587.735306617242, 10589.523471443803, 569419.0, 573207.0, 586184.0, 601457.0, 601695.0, 122160.77839221494, 127815.36092319665, 133561.0, 133868.33065834804, 135045.0, 1008.5814387029301, 1017.9643712470028, 1060.2631171336002, 1087.858578384805, 1088.4910281800526, 14523.910706696828, 16889.239984200496, 17529.0, 17776.822231777518, 17785.0, 38175.6182438172, 49497.75251123722, 50039.24941125959, 50504.0, 50661.74669126631, 59445.0, 74150.0, 75832.62083689547, 76745.0, 77986.0, 8229.670813167406, 8233.259006689426, 8279.83440331178, 8301.875471868048, 8610.095939926217, 11802.291862488279, 12210.0, 12390.504379824706, 12514.624561263177, 12537.686557835763, 10573.9471302643, 10927.180461465263, 10989.120870330515, 11102.500387482422, 11180.832287515532, 10431.16550675929, 10482.842757358798, 10648.094911932572, 10732.248742587944, 10907.727306817393, 10955.452227388758, 11073.94463027712, 11080.445977700922, 11358.772824543628, 11376.033037191932, 7199.424046076197, 8025.9197408025175, 8228.876566851382, 8254.958725206336, 8255.958720206361, 8369.581520924052, 10243.94878025605, 10798.082163015917, 11035.558577656804, 11083.50124244427], "value": 10340.381401776016, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.1245740098545, 1583.0897234090398, 1619.9757003644943, 1856.6286742651473, 1994.910229039694, 8015.0381954165505, 8768.956155219223, 9014.323925705565, 9049.547522623876, 9150.588223529941, 8778.780530486742, 8985.865212021808, 9002.54987250638, 9009.819803603925, 9013.41412808167, 2113.186423227061, 2197.8351623628214, 2198.56028794241, 2428.64784606232, 2466.371075375777, 2214.5792299463133, 2232.810211132056, 2389.1996181279205, 2460.372604985726, 2477.2815883393882, 1995.5011247188204, 2309.7690230976937, 2311.595470792613, 2317.8145748340094, 2318.2929206592066, 2391.6532102845076, 2445.76765207305, 2448.069733501276, 2448.791852692523, 2449.191766716979, 2153.6446486329737, 2383.4041489627593, 2464.4208610976407, 2465.1125594785863, 2483.7392073832348, 8959.01450840407, 9022.872140982377, 9044.728658140266, 9048.276137908952, 9051.321150913642, 5328.307320048369, 5474.890502189974, 5480.588955828329, 5495.368032676253, 5514.448555144453, 3100.7829451938373, 3489.2672538766888, 3545.3441113394106, 3626.2203626220225, 3651.8904432867057, 996.3772642098688, 1042.7601651620093, 1042.8696412948382, 1046.6807623674777, 1054.2093429927554, 10180.23648226386, 12040.939795301056, 12044.819327710178, 12045.0, 12051.15641905067, 4822.68652537584, 4884.902301954012, 4966.205407134841, 4966.379202599675, 5012.874678133003, 10281.023302786223, 10290.485475726184, 10294.588216471257, 10314.226433017553, 10322.967703229617, 5538.972305138448, 5543.6396634218645, 5545.500904918496, 5552.361478430033, 5557.805476808292, 4514.503404625489, 4944.9368385797225, 4954.529319714621, 5016.724080175552, 5065.265536497187, 3429.868143512635, 3802.1825307559006, 4019.0153412414147, 4056.6551843093075, 4128.401381799622, 1336.2650542201945, 1357.0907491980397, 1358.9728205436033, 1368.0765483298733, 1387.6600232942792, 800.3277247112337, 859.9398042137116, 860.6901515454431, 909.3634455880909, 975.3855071305032, 5597.692126932976, 5687.544996400361, 5878.0, 5939.07944268639, 5963.880722385528, 892.9909202601019, 912.2428384440898, 937.9437233765996, 952.9285303602257, 976.345848281653, 3841.4429907663234, 3935.2129574085297, 3996.540397854226, 3999.300122478555, 4008.378701301303, 10432.217583681255, 10971.506282217319, 11100.111991040698, 11312.151588630886, 11315.094792416587, 9664.371815831948, 10980.670579882617, 11059.391733454577, 11184.496697648625, 11213.775724485464, 8375.8324833503, 8433.620487078097, 8472.661093556311, 8533.53065581399, 8582.828343433097, 5740.0, 5866.911996319973, 5884.146798714077, 5902.763889444374, 5947.583669143291, 9844.0, 9901.15840153595, 10239.7440063999, 10244.180465562587, 10352.844707329245, 10462.476876156314, 10537.841932370868, 10541.631042913461, 10555.577776888838, 10563.419011954109, 6979.476539259677, 7019.824504387431, 7112.359887609933, 7241.782746517613, 7250.456215783732, 8302.16978302189, 8557.957210213908, 8561.14388561139, 8577.571121443778, 8609.397342186237, 7267.30960558746, 7459.40324774006, 7460.776176714708, 7587.772366829004, 7624.656890439833, 7372.705091796268, 7486.850262994819, 7505.024346834876, 7565.0, 7642.312191902755, 7046.436285097282, 7048.8590228194125, 7128.501004929605, 7130.750423735144, 7141.071660684078, 4795.712257264575, 4889.071076495455, 4952.257161425815, 5003.34956455673, 5125.692458452505, 4952.727599981891, 5004.724740139327, 5056.696598204119, 5100.081985242692, 5294.788208471618], "value": 4647.4516542146375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.6811348084512, 308.8193406856988, 320.967903209679, 324.8278412441404, 326.45156137688673, 4484.080763443494, 4706.458757242918, 4862.759996200968, 5050.267711181877, 5271.3147290852185, 4439.445069366329, 5027.737518116853, 5217.982493413786, 5277.551408130304, 5284.260203571503, 3758.812059397033, 4045.7977101144825, 4058.472398588194, 4067.1052368478886, 4071.063655359269, 3706.2402207547525, 3804.9429258561145, 4088.468499095128, 4089.4440974391005, 4098.8360465581345, 3529.535242874204, 3583.7133029357587, 4058.2289365020697, 4069.9186016279805, 4086.427900093989, 3410.6077801052948, 3683.5395575553057, 4054.4121102440126, 4084.2852500812237, 4086.2849001424634, 3536.2043540203504, 3537.8584856605703, 3554.8091389384585, 3564.750467467278, 3574.767640103385, 4906.484819093979, 4953.069228396398, 5039.44566097729, 5054.609982254872, 5109.591232701375, 4624.630029597624, 4626.537346265347, 4630.236011058171, 4655.86032419028, 4673.789679464398, 5130.615203859688, 5150.716710580917, 5283.260343551906, 5533.529649979716, 5560.638558493685, 3583.0, 3828.717379677811, 3841.673457756094, 3845.4039623858066, 3846.480640147126, 5780.0, 5863.5309175265875, 5866.6186697864505, 5873.706314684248, 5879.382664820175, 3540.5574303212097, 3643.5809881863397, 3663.7435379523176, 3895.0846551060476, 3911.3155197840265, 3795.2409518096492, 4062.9187416252103, 4065.0853557949226, 4086.8773936781945, 4108.568600296986, 3866.226754649025, 4605.171069207575, 4649.3026046093355, 4685.0, 4687.29690546414, 5018.372703412074, 5410.350757908997, 5608.439156084359, 5622.775088996476, 5623.372924463698, 1261.4954018392716, 1286.633309506786, 1336.3295880149813, 1380.1167252958114, 1400.6288333591606, 842.928351090152, 846.1496196322779, 866.6360128745941, 866.7659731872359, 880.5905254056894, 3722.40441529354, 3890.840583476141, 4098.4467096941535, 4275.140313963374, 4282.914341713148, 4285.785710714452, 4302.913087152729, 4335.674824388184, 4714.175019371596, 4740.463851839249, 3871.128995975883, 3978.5677156223737, 3984.9203015940097, 4223.556526564728, 4347.7173983690955, 2080.583883223361, 2341.637046257833, 2388.7611238875975, 2436.439618887666, 2584.5834145074414, 4258.787060646955, 4332.155229730172, 4679.467002413225, 4817.710937343771, 5072.746362681851, 4346.543612920661, 4391.978040109779, 4488.910221795546, 4786.880327991828, 4988.55103040728, 4113.3957756475, 4478.507364190002, 4788.539495453851, 4803.927941080887, 4989.154013015208, 4026.288827248466, 4426.44669416323, 4567.223571992836, 4573.467888257471, 4769.0431932202255, 4525.0, 4550.88622784433, 4570.925832429443, 4594.655400844883, 4740.407449068866, 4060.939085913773, 4561.0, 4792.400949881265, 5016.846125391107, 5040.294358789699, 3458.948115778215, 3459.668027809362, 3791.829367678516, 3914.807707683706, 3937.2322397132284, 5506.732282481109, 5670.546356291405, 5683.204351390726, 5704.857378565569, 5776.682282474504, 4572.6799124061, 5447.918281225864, 5486.588505861996, 5555.805546805843, 5563.884224834106, 5147.819826306061, 5189.713635126045, 5407.648502847303, 5546.944530554642, 5560.221568980427, 4629.351890735233, 5357.973210134081, 5380.0, 5382.273393091882, 5382.30030096085, 4769.546893045155, 5253.343332083489, 5501.231656315277, 5503.11950087984, 5557.972210138923, 4566.383538222298, 5297.020051290524, 5370.301860758076, 5393.759435329975, 5414.7021913795115], "value": 3842.597840387028, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.4425184578344, 652.8922727749922, 683.521534925552, 716.0583832260577, 721.0842230367433, 15008.549743507698, 15215.315310811013, 15454.304556294966, 15465.76801347978, 15478.4582539611, 11804.468798904049, 13860.861391386083, 14280.571582852504, 14287.999840011204, 14386.856131438682, 893.8703887936246, 899.1997122560927, 911.7492689510372, 913.1462082952461, 913.7304495173906, 724.3734169942987, 747.36100633958, 877.0265005843509, 903.8192361527688, 906.2659246010719, 970.1075010989871, 971.9659811906585, 975.3026585991015, 976.1214906584061, 977.1254727019314, 755.2409828122702, 969.0358093696747, 969.3069455339431, 971.8930917599093, 972.1882228339346, 694.1808665774385, 716.187127610161, 718.3211864787778, 718.3642476408364, 720.8053825467147, 12067.396630168545, 14436.989410741253, 14867.851321486754, 14909.776353354708, 14918.403263869432, 12121.878781212252, 12144.696382590417, 12194.0, 12510.624681259576, 12636.620901372973, 10684.145268378512, 10719.142468602493, 11086.722831929186, 11160.609378671708, 11411.315321080761, 4003.399490076512, 4118.19695159444, 4129.339305711102, 4138.8344466221315, 4247.617714405687, 72197.0, 75528.62235688786, 75716.0, 79373.0, 81065.59467202685, 9350.0, 9448.716538503855, 9505.42967421957, 9597.040295970486, 10106.494675266207, 20905.477363066046, 20923.895380523, 20946.95265236732, 20968.266110686054, 20998.0, 7063.081799366049, 7133.964330178316, 7198.892016619755, 7450.105987281561, 7775.844483110419, 3878.146807702301, 3906.980465097656, 3952.8023598819946, 3953.2291203215673, 3964.246793109358, 3402.863885444604, 3505.5618047744033, 3516.5955915069585, 3523.6828685418436, 3526.7531272810656, 631.5263552335748, 665.2017578905333, 676.1615596660106, 698.4447364345358, 706.0080586775589, 548.7844424599531, 591.4765432592101, 597.2713289786453, 608.0787606775739, 635.7726073113861, 8054.436189466798, 8203.261706446328, 8323.791905202419, 8333.125021872738, 8394.622241999125, 584.3221862639318, 585.9765609375662, 592.1088761414044, 606.9089636554464, 609.5641616244407, 3142.4972004479173, 3907.0623050468253, 3914.9021274467796, 3949.624785645359, 3988.8603898863403, 96442.51778741062, 99067.50466247623, 99396.00603994013, 100878.47803804964, 100954.0, 67891.30271743245, 85740.14259857322, 86375.56812216024, 86600.0, 87155.8211044729, 67901.98147027798, 68556.6572167136, 68812.65593672, 72453.637731811, 72972.0, 8068.112507624274, 8072.9192708074515, 8266.090730019572, 8326.750197494084, 8329.250367466957, 42447.28689917009, 43718.78140609276, 44062.77968610136, 44597.66207013795, 46354.76822615865, 50842.0, 62183.0, 62649.68675156595, 63928.0, 64463.67768161317, 10893.291936024134, 11068.557257709923, 11101.944490277498, 11246.493907774333, 11399.943000284946, 11992.700182495508, 12140.210886292363, 12382.5666101686, 12470.750584988431, 12920.935395323339, 11526.366049867338, 11671.241369310968, 11696.824547631622, 11782.293062416282, 11876.0, 11225.270357426742, 11306.94346528262, 11309.547618095185, 11663.0, 11718.531258749554, 11154.386508742096, 11211.383373914186, 11217.102631789274, 11590.95681388679, 11710.063194944214, 7847.803804904923, 9424.387414818015, 9588.232941364535, 9676.27427942893, 9768.120869121813, 8659.350548708999, 9205.86191207119, 9229.307801914749, 9600.231981441328, 9631.566579504079], "value": 6888.437016005959, "unit": "score"}], "name": "dromaeo_css", "value": 7211.404911480079, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 113.0, 112.0, 112.0, 113.0, 112.0, 114.0, 113.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 108.0, 141.0, 199.0, 121.0, 136.0, 136.0, 117.0, 167.0, 118.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 185.0, 187.0, 184.0, 186.0, 184.0, 184.0, 186.0, 179.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 58.0, 126.0, 59.0, 83.0, 79.0, 82.0, 126.0, 56.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 145.0, 110.0, 100.0, 77.0, 102.0, 80.0, 80.0, 102.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 86.0, 90.0, 87.0, 90.0, 86.0, 90.0, 96.0, 130.0, 128.0], "value": 97.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 94.0, 125.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 85.0, 85.0, 84.0, 84.0, 85.0, 84.0, 86.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 47.0, 81.0, 49.0, 52.0, 58.0, 79.0, 168.0, 76.0, 57.0], "value": 71.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 79.0, 72.0, 92.0, 95.0, 65.0, 69.0, 67.0, 67.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 87.0, 85.0, 81.0, 84.0, 81.0, 84.0, 85.0, 85.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 134.0, 138.0, 137.0, 135.0, 135.0, 160.0, 246.0, 156.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 187.0, 175.0, 168.0, 167.0, 167.0, 214.0, 187.0, 166.0, 166.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 69.0, 66.0, 65.0, 65.0, 67.0, 67.0, 65.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1521.1999999999998, "alertThreshold": 2.0}]} 01:55:51 INFO - Return code: 0 01:55:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:55:51 ERROR - # TBPL SUCCESS # 01:55:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:55:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:55:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:55:51 INFO - 2016-05-13 01:55:51.779 screenresolution[2001:11983] starting screenresolution argv=screenresolution get 01:55:51 INFO - 2016-05-13 01:55:51.782 screenresolution[2001:11983] Display 0: 1600x1200x32@60 01:55:51 INFO - 2016-05-13 01:55:51.788 screenresolution[2002:11987] starting screenresolution argv=screenresolution list 01:55:51 INFO - Available Modes on Display 0 01:55:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:55:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:55:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:55:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:55:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:55:52 INFO - Intel Iris: 01:55:52 INFO - Chipset Model: Intel Iris 01:55:52 INFO - Type: GPU 01:55:52 INFO - Bus: Built-In 01:55:52 INFO - VRAM (Dynamic, Max): 1536 MB 01:55:52 INFO - Vendor: Intel (0x8086) 01:55:52 INFO - Device ID: 0x0a2e 01:55:52 INFO - Revision ID: 0x0009 01:55:52 INFO - Displays: 01:55:52 INFO - Display: 01:55:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:55:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:55:52 INFO - Display Serial Number: 1600x1200 60 01:55:52 INFO - Main Display: Yes 01:55:52 INFO - Mirror: Off 01:55:52 INFO - Online: Yes 01:55:52 INFO - Rotation: Supported 01:55:52 INFO - Return code: 0 01:55:52 INFO - Running post-action listener: _resource_record_post_action 01:55:52 INFO - ##### 01:55:52 INFO - ##### Finished run-tests step (success) 01:55:52 INFO - ##### 01:55:52 INFO - Running post-run listener: _resource_record_post_run 01:55:53 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 163840; Write bytes: 692988928; Read time: 2; Write time: 1936 01:55:53 INFO - TinderboxPrint: CPU usage
54.0% 01:55:53 INFO - TinderboxPrint: I/O read bytes / time
163,840 / 2 01:55:53 INFO - TinderboxPrint: I/O write bytes / time
692,988,928 / 1,936 01:55:53 INFO - TinderboxPrint: CPU idle
2,234.1 (46.0%) 01:55:53 INFO - TinderboxPrint: CPU system
969.3 (20.0%) 01:55:53 INFO - TinderboxPrint: CPU user
1,650.4 (34.0%) 01:55:53 INFO - TinderboxPrint: Swap in / out
393,555,968 / 0 01:55:53 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 383834624; Write bytes: 379446272; Read time: 28969; Write time: 789 01:55:53 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 163840; Write bytes: 294148096; Read time: 2; Write time: 1093 01:55:53 INFO - Running post-run listener: _upload_blobber_files 01:55:53 INFO - Blob upload gear active. 01:55:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:55:53 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 01:55:53 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'] 01:55:53 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 01:55:53 INFO - (blobuploader) - INFO - Open directory for files ... 01:55:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:55:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:55:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:55 INFO - (blobuploader) - INFO - Done attempting. 01:55:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:55:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:55:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:55:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:55:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:55:56 INFO - (blobuploader) - INFO - Done attempting. 01:55:56 INFO - (blobuploader) - INFO - Iteration through files over. 01:55:56 INFO - Return code: 0 01:55:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:55:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:55:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a86098bbbf13654e5d0a4889ad4caeac84393b101cfaa4e197d8b8cc335d71dbd8c893105ce53dacaaf300388d61c3fda0915511d8e01a3067408ee1b6a6a74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaccb4770035e56dbbbdf6f0865a3d2eeec2fa789dc4b53ec5a3430baebdf3b59d6f6509b8c35c8be2637092e8b38df01fc93f027042544e93ccb3ae42f09dde"} 01:55:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:55:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:55:56 INFO - Contents: 01:55:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a86098bbbf13654e5d0a4889ad4caeac84393b101cfaa4e197d8b8cc335d71dbd8c893105ce53dacaaf300388d61c3fda0915511d8e01a3067408ee1b6a6a74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaccb4770035e56dbbbdf6f0865a3d2eeec2fa789dc4b53ec5a3430baebdf3b59d6f6509b8c35c8be2637092e8b38df01fc93f027042544e93ccb3ae42f09dde"} 01:55:56 INFO - Running post-run listener: copy_logs_to_upload_dir 01:55:56 INFO - Copying logs to upload dir... 01:55:56 INFO - mkdir: /builds/slave/test/build/upload/logs 01:55:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.811724 ========= master_lag: 5.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-13 01:56:01.351543) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 01:56:01.359439) ========= 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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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/3a86098bbbf13654e5d0a4889ad4caeac84393b101cfaa4e197d8b8cc335d71dbd8c893105ce53dacaaf300388d61c3fda0915511d8e01a3067408ee1b6a6a74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaccb4770035e56dbbbdf6f0865a3d2eeec2fa789dc4b53ec5a3430baebdf3b59d6f6509b8c35c8be2637092e8b38df01fc93f027042544e93ccb3ae42f09dde"} build_url:https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011101 build_url: 'https://queue.taskcluster.net/v1/task/JVmcbNcoT_e0DOUE-GFtGw/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/3a86098bbbf13654e5d0a4889ad4caeac84393b101cfaa4e197d8b8cc335d71dbd8c893105ce53dacaaf300388d61c3fda0915511d8e01a3067408ee1b6a6a74", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eaccb4770035e56dbbbdf6f0865a3d2eeec2fa789dc4b53ec5a3430baebdf3b59d6f6509b8c35c8be2637092e8b38df01fc93f027042544e93ccb3ae42f09dde"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 01:56:01.392740) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 01:56:01.393072) ========= 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.JXI1b1ABTp/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.uoxR9jSM9z/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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.005151 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 01:56:01.441693) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 01:56:01.441991) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 01:56:01.445326) ========= ========= Total master_lag: 5.31 =========