builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0018 starttime: 1465351227.56 results: success (0) buildid: 20160607155849 builduid: 8028f40b237848a58f590068220a09ee revision: cad514ad49c199e823a92e8c8d27e16c22c3cac7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:27.562290) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:27.562734) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 5 secs) (at 2016-06-07 19:00:27.563010) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 5 secs) (at 2016-06-07 19:00:32.610048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:32.610615) ========= 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.fE9231BAdz/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.dPsc0mCVUS/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-07 19:00:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-06-07 19:00:32 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108355 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:32.740740) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:32.741063) ========= 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.fE9231BAdz/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.dPsc0mCVUS/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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.020445 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:32.790192) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 19:00:32.790542) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev cad514ad49c199e823a92e8c8d27e16c22c3cac7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev cad514ad49c199e823a92e8c8d27e16c22c3cac7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fE9231BAdz/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.dPsc0mCVUS/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-07 19:00:32,868 truncating revision to first 12 chars 2016-06-07 19:00:32,868 Setting DEBUG logging. 2016-06-07 19:00:32,868 attempt 1/10 2016-06-07 19:00:32,868 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/cad514ad49c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 19:00:33,859 unpacking tar archive at: autoland-cad514ad49c1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.247393 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 19:00:34.058647) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:34.059016) ========= script_repo_revision: cad514ad49c199e823a92e8c8d27e16c22c3cac7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:34.062634) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:34.062931) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 19:00:34.080948) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-06-07 19:00:34.081338) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fE9231BAdz/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.dPsc0mCVUS/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:00:34 INFO - MultiFileLogger online at 20160607 19:00:34 in /builds/slave/test 19:00:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 19:00:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:00:34 INFO - {'append_to_log': False, 19:00:34 INFO - 'base_work_dir': '/builds/slave/test', 19:00:34 INFO - 'blob_upload_branch': 'Autoland', 19:00:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:00:34 INFO - 'branch': 'Autoland', 19:00:34 INFO - 'buildbot_json_path': 'buildprops.json', 19:00:34 INFO - 'config_files': ('talos/mac_config.py',), 19:00:34 INFO - 'default_actions': ('clobber', 19:00:34 INFO - 'read-buildbot-config', 19:00:34 INFO - 'download-and-extract', 19:00:34 INFO - 'populate-webroot', 19:00:34 INFO - 'create-virtualenv', 19:00:34 INFO - 'install', 19:00:34 INFO - 'run-tests'), 19:00:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:00:34 INFO - 'download_minidump_stackwalk': True, 19:00:34 INFO - 'download_symbols': 'ondemand', 19:00:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:00:34 INFO - 'tooltool.py': '/tools/tooltool.py', 19:00:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:00:34 INFO - '/tools/misc-python/virtualenv.py')}, 19:00:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:00:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:00:34 INFO - 'installer_path': 'installer.exe', 19:00:34 INFO - 'log_level': 'info', 19:00:34 INFO - 'log_name': 'talos', 19:00:34 INFO - 'log_to_console': True, 19:00:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:00:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:00:34 INFO - 'opt_config_files': (), 19:00:34 INFO - 'pip_index': False, 19:00:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:00:34 INFO - 'cmd': ('bash', 19:00:34 INFO - '-c', 19:00:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:00:34 INFO - 'enabled': True, 19:00:34 INFO - 'halt_on_failure': False, 19:00:34 INFO - 'name': 'check_screen_resolution'},), 19:00:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:00:34 INFO - 'cmd': ('bash', 19:00:34 INFO - '-c', 19:00:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:00:34 INFO - 'enabled': True, 19:00:34 INFO - 'halt_on_failure': False, 19:00:34 INFO - 'name': 'check_screen_resolution'},), 19:00:34 INFO - 'run_cmd_checks_enabled': True, 19:00:34 INFO - 'sps_profile': False, 19:00:34 INFO - 'sps_profile_interval': 0, 19:00:34 INFO - 'suite': 'dromaeojs', 19:00:34 INFO - 'system_bits': '32', 19:00:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:00:34 INFO - 'title': 't-yosemite-r7-0018', 19:00:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:00:34 INFO - 'use_talos_json': True, 19:00:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:00:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:00:34 INFO - 'work_dir': 'build'} 19:00:34 INFO - ##### 19:00:34 INFO - ##### Running clobber step. 19:00:34 INFO - ##### 19:00:34 INFO - Running pre-action listener: _resource_record_pre_action 19:00:34 INFO - Running main action method: clobber 19:00:34 INFO - rmtree: /builds/slave/test/build 19:00:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:00:35 INFO - Running post-action listener: _resource_record_post_action 19:00:35 INFO - ##### 19:00:35 INFO - ##### Finished clobber step (success) 19:00:35 INFO - ##### 19:00:35 INFO - ##### 19:00:35 INFO - ##### Running read-buildbot-config step. 19:00:35 INFO - ##### 19:00:35 INFO - Running pre-action listener: _resource_record_pre_action 19:00:35 INFO - Running main action method: read_buildbot_config 19:00:35 INFO - Using buildbot properties: 19:00:35 INFO - { 19:00:35 INFO - "project": "", 19:00:35 INFO - "product": "firefox", 19:00:35 INFO - "script_repo_revision": "production", 19:00:35 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 19:00:35 INFO - "repository": "", 19:00:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 19:00:35 INFO - "buildid": "20160607155849", 19:00:35 INFO - "pgo_build": "False", 19:00:35 INFO - "basedir": "/builds/slave/test", 19:00:35 INFO - "buildnumber": 0, 19:00:35 INFO - "slavename": "t-yosemite-r7-0018", 19:00:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:00:35 INFO - "platform": "yosemite_r7", 19:00:35 INFO - "scheduler": "tests-autoland-macosx64-talos", 19:00:35 INFO - "branch": "autoland", 19:00:35 INFO - "repo_path": "integration/autoland", 19:00:35 INFO - "revision": "cad514ad49c199e823a92e8c8d27e16c22c3cac7", 19:00:35 INFO - "stage_platform": "macosx64", 19:00:35 INFO - "builduid": "8028f40b237848a58f590068220a09ee", 19:00:35 INFO - "slavebuilddir": "test" 19:00:35 INFO - } 19:00:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:00:35 INFO - Running post-action listener: _resource_record_post_action 19:00:35 INFO - ##### 19:00:35 INFO - ##### Finished read-buildbot-config step (success) 19:00:35 INFO - ##### 19:00:35 INFO - ##### 19:00:35 INFO - ##### Running download-and-extract step. 19:00:35 INFO - ##### 19:00:35 INFO - Running pre-action listener: _resource_record_pre_action 19:00:35 INFO - Running main action method: download_and_extract 19:00:35 INFO - mkdir: /builds/slave/test/build/tests 19:00:35 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')]} 19:00:35 INFO - https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:00:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:00:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:00:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/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 19:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/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 19:00:36 INFO - Downloaded 1199 bytes. 19:00:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:00:36 INFO - Using the following test package requirements: 19:00:36 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:00:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:00:36 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:00:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:00:36 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:00:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:00:36 INFO - u'jsshell-mac.zip'], 19:00:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:00:36 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:00:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:00:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:00:36 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:00:36 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:00:36 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:00:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:00:36 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:00:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:00:36 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:00:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:00:36 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')]} 19:00:36 INFO - https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:00:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:00:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:00:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/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 19:00:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/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 19:00:39 INFO - Downloaded 18684753 bytes. 19:00:39 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'] 19:00:39 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 19:00:42 INFO - Return code: 0 19:00:42 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 19:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:42 INFO - https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:00:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:00:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:00:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/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 19:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/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 19:00:44 INFO - Downloaded 18684753 bytes. 19:00:44 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'] 19:00:44 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 19:00:47 INFO - Return code: 0 19:00: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')]} 19:00:47 INFO - https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:00:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:00:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:00:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/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 19:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/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 19:00:49 INFO - Downloaded 10834034 bytes. 19:00:49 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'] 19:00:49 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 19:00:49 INFO - Return code: 0 19:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:49 INFO - https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:00:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:00:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:00:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:00:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KW0JO5OSRC2uahiDReF20Q/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 19:00:52 INFO - Downloaded 110485429 bytes. 19:00:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:00:52 INFO - mkdir: /builds/slave/test/properties 19:00:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:00:52 INFO - Writing to file /builds/slave/test/properties/build_url 19:00:52 INFO - Contents: 19:00:52 INFO - build_url:https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:00:53 INFO - Running post-action listener: _resource_record_post_action 19:00:53 INFO - Running post-action listener: set_extra_try_arguments 19:00:53 INFO - ##### 19:00:53 INFO - ##### Finished download-and-extract step (success) 19:00:53 INFO - ##### 19:00:53 INFO - ##### 19:00:53 INFO - ##### Running populate-webroot step. 19:00:53 INFO - ##### 19:00:53 INFO - Running pre-action listener: _resource_record_pre_action 19:00:53 INFO - Running main action method: populate_webroot 19:00:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:00:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:00:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:00:53 INFO - '--tppagecycles', 19:00:53 INFO - '7'], 19:00:53 INFO - 'tests': ['tsvgm']}}, 19:00:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:00:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:00:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:00:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:00:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:53 INFO - 'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:53 INFO - 'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['damp', 'tps']}, 19:00:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:53 INFO - 'tests': ['damp', 'tps']}, 19:00:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['dromaeo_dom']}, 19:00:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:00:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['a11yr', 19:00:53 INFO - 'ts_paint', 19:00:53 INFO - 'tpaint', 19:00:53 INFO - 'sessionrestore', 19:00:53 INFO - 'sessionrestore_no_auto_restore', 19:00:53 INFO - 'tabpaint']}, 19:00:53 INFO - 'other-e10s': {'tests': ['a11yr', 19:00:53 INFO - 'ts_paint', 19:00:53 INFO - 'tpaint', 19:00:53 INFO - 'sessionrestore', 19:00:53 INFO - 'sessionrestore_no_auto_restore', 19:00:53 INFO - 'tabpaint']}, 19:00:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:00:53 INFO - 'ts_paint', 19:00:53 INFO - 'tpaint', 19:00:53 INFO - 'sessionrestore', 19:00:53 INFO - 'sessionrestore_no_auto_restore', 19:00:53 INFO - 'tabpaint']}, 19:00:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:00:53 INFO - 'ts_paint', 19:00:53 INFO - 'tpaint', 19:00:53 INFO - 'sessionrestore', 19:00:53 INFO - 'sessionrestore_no_auto_restore', 19:00:53 INFO - 'tabpaint']}, 19:00:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['a11yr', 19:00:53 INFO - 'ts_paint', 19:00:53 INFO - 'tpaint', 19:00:53 INFO - 'sessionrestore', 19:00:53 INFO - 'sessionrestore_no_auto_restore', 19:00:53 INFO - 'tabpaint']}, 19:00:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['a11yr', 19:00:53 INFO - 'ts_paint', 19:00:53 INFO - 'tpaint', 19:00:53 INFO - 'sessionrestore', 19:00:53 INFO - 'sessionrestore_no_auto_restore', 19:00:53 INFO - 'tabpaint']}, 19:00:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['tsvgx', 19:00:53 INFO - 'tsvgr_opacity', 19:00:53 INFO - 'tart', 19:00:53 INFO - 'tscrollx', 19:00:53 INFO - 'cart']}, 19:00:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:00:53 INFO - 'tsvgr_opacity', 19:00:53 INFO - 'tart', 19:00:53 INFO - 'tscrollx', 19:00:53 INFO - 'cart']}, 19:00:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:53 INFO - 'talos_options': ['--disable-e10s'], 19:00:53 INFO - 'tests': ['tp5o']}, 19:00:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:53 INFO - 'tests': ['tp5o']}, 19:00:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:53 INFO - 'talos_options': ['--disable-e10s', 19:00:53 INFO - '--xperf_path', 19:00:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:53 INFO - 'tests': ['tp5n']}, 19:00:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:53 INFO - 'talos_options': ['--xperf_path', 19:00:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:53 INFO - 'tests': ['tp5n']}}, 19:00:53 INFO - 'talos.zip': {'path': '', 19:00:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:00:53 INFO - Running post-action listener: _resource_record_post_action 19:00:53 INFO - ##### 19:00:53 INFO - ##### Finished populate-webroot step (success) 19:00:53 INFO - ##### 19:00:53 INFO - ##### 19:00:53 INFO - ##### Running create-virtualenv step. 19:00:53 INFO - ##### 19:00:53 INFO - Running pre-action listener: _resource_record_pre_action 19:00:53 INFO - Running main action method: create_virtualenv 19:00:53 INFO - Creating virtualenv /builds/slave/test/build/venv 19:00:53 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 19:00:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:00:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:00:53 INFO - Using real prefix '/tools/python27' 19:00:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:00:54 INFO - Installing distribute.............................................................................................................................................................................................done. 19:00:57 INFO - Installing pip.................done. 19:00:57 INFO - Return code: 0 19:00:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:00: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')]} 19:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00: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')]} 19:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e3d80a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, '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.fE9231BAdz/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.dPsc0mCVUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:00:57 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 19:00:57 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 19:00:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:00:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:00:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:00:57 INFO - 'HOME': '/Users/cltbld', 19:00:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:00:57 INFO - 'LOGNAME': 'cltbld', 19:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:57 INFO - 'MOZ_NO_REMOTE': '1', 19:00:57 INFO - 'NO_EM_RESTART': '1', 19:00:57 INFO - 'PAGER': '/bin/cat', 19:00:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:57 INFO - 'PWD': '/builds/slave/test', 19:00:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:00:57 INFO - 'SHELL': '/bin/bash', 19:00:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:00:57 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:00:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:00:57 INFO - 'USER': 'cltbld', 19:00:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:00:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:57 INFO - 'XPC_FLAGS': '0x0', 19:00:57 INFO - 'XPC_SERVICE_NAME': '0', 19:00:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:57 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 19:00:57 INFO - Cleaning up... 19:00:57 INFO - Return code: 0 19:00:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:00: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')]} 19:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00: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')]} 19:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e3d80a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, '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.fE9231BAdz/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.dPsc0mCVUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:00:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:00:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:00:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:00:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:00:57 INFO - 'HOME': '/Users/cltbld', 19:00:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:00:57 INFO - 'LOGNAME': 'cltbld', 19:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:57 INFO - 'MOZ_NO_REMOTE': '1', 19:00:57 INFO - 'NO_EM_RESTART': '1', 19:00:57 INFO - 'PAGER': '/bin/cat', 19:00:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:57 INFO - 'PWD': '/builds/slave/test', 19:00:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:00:57 INFO - 'SHELL': '/bin/bash', 19:00:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:00:57 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:00:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:00:57 INFO - 'USER': 'cltbld', 19:00:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:00:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:57 INFO - 'XPC_FLAGS': '0x0', 19:00:57 INFO - 'XPC_SERVICE_NAME': '0', 19:00:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:57 INFO - Downloading/unpacking psutil>=3.1.1 19:00:57 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 19:00:57 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 19:00:57 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 19:00:57 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 19:00:57 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 19:00:57 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 19:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:01:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:00 INFO - Installing collected packages: psutil 19:01:00 INFO - Running setup.py install for psutil 19:01:00 INFO - building 'psutil._psutil_osx' extension 19:01:00 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 19:01:00 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 19:01:00 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 19:01:01 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 19:01:01 INFO - building 'psutil._psutil_posix' extension 19:01:01 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 19:01:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:01:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:01:01 INFO - ^ 19:01:01 INFO - 1 warning generated. 19:01:01 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 19:01:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:01:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:01:01 INFO - Successfully installed psutil 19:01:01 INFO - Cleaning up... 19:01:01 INFO - Return code: 0 19:01:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:01:01 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')]} 19:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:01 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')]} 19:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e3d80a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, '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.fE9231BAdz/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.dPsc0mCVUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:01:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:01:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:01:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:01:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:01 INFO - 'HOME': '/Users/cltbld', 19:01:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:01 INFO - 'LOGNAME': 'cltbld', 19:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:01 INFO - 'MOZ_NO_REMOTE': '1', 19:01:01 INFO - 'NO_EM_RESTART': '1', 19:01:01 INFO - 'PAGER': '/bin/cat', 19:01:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:01 INFO - 'PWD': '/builds/slave/test', 19:01:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:01 INFO - 'SHELL': '/bin/bash', 19:01:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:01:01 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:01:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:01 INFO - 'USER': 'cltbld', 19:01:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:01 INFO - 'XPC_FLAGS': '0x0', 19:01:01 INFO - 'XPC_SERVICE_NAME': '0', 19:01:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:01:04 INFO - Installing collected packages: mozsystemmonitor 19:01:04 INFO - Running setup.py install for mozsystemmonitor 19:01:04 INFO - Successfully installed mozsystemmonitor 19:01:04 INFO - Cleaning up... 19:01:04 INFO - Return code: 0 19:01:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e3d80a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, '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.fE9231BAdz/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.dPsc0mCVUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:01:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:04 INFO - 'HOME': '/Users/cltbld', 19:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:04 INFO - 'LOGNAME': 'cltbld', 19:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:04 INFO - 'MOZ_NO_REMOTE': '1', 19:01:04 INFO - 'NO_EM_RESTART': '1', 19:01:04 INFO - 'PAGER': '/bin/cat', 19:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:04 INFO - 'PWD': '/builds/slave/test', 19:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:04 INFO - 'SHELL': '/bin/bash', 19:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:01:04 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:04 INFO - 'USER': 'cltbld', 19:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:04 INFO - 'XPC_FLAGS': '0x0', 19:01:04 INFO - 'XPC_SERVICE_NAME': '0', 19:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:04 INFO - Downloading/unpacking blobuploader==1.2.4 19:01:04 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 19:01:04 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 19:01:04 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 19:01:04 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 19:01:04 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 19:01:04 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 19:01:07 INFO - Downloading blobuploader-1.2.4.tar.gz 19:01:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:01:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:01:07 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 19:01:07 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 19:01:07 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 19:01:07 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 19:01:07 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 19:01:07 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 19:01:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:01:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:01:07 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 19:01:07 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 19:01:07 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 19:01:07 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 19:01:07 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 19:01:07 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 19:01:08 INFO - Downloading docopt-0.6.1.tar.gz 19:01:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:01:08 INFO - Installing collected packages: blobuploader, requests, docopt 19:01:08 INFO - Running setup.py install for blobuploader 19:01:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:01:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:01:08 INFO - Running setup.py install for requests 19:01:09 INFO - Running setup.py install for docopt 19:01:09 INFO - Successfully installed blobuploader requests docopt 19:01:09 INFO - Cleaning up... 19:01:09 INFO - Return code: 0 19:01:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e3d80a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, '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.fE9231BAdz/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.dPsc0mCVUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:01:09 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 19:01:09 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 19:01:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:01:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:09 INFO - 'HOME': '/Users/cltbld', 19:01:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:09 INFO - 'LOGNAME': 'cltbld', 19:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:09 INFO - 'MOZ_NO_REMOTE': '1', 19:01:09 INFO - 'NO_EM_RESTART': '1', 19:01:09 INFO - 'PAGER': '/bin/cat', 19:01:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:09 INFO - 'PWD': '/builds/slave/test', 19:01:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:09 INFO - 'SHELL': '/bin/bash', 19:01:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:01:09 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:01:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:09 INFO - 'USER': 'cltbld', 19:01:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:09 INFO - 'XPC_FLAGS': '0x0', 19:01:09 INFO - 'XPC_SERVICE_NAME': '0', 19:01:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:01:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-ac2vGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:01:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-yxAwyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:01:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-gUDgi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:01:09 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-adjyZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-19SDZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-LDbAQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-uJBIzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-omRM9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-knZ0pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-__RJpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-MwXYhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-QBbJgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-MBcxmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:01:10 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-bDY5vX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:11 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-UV5NZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:01:11 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-NZ7Wxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:01:11 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-cWqIKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:01:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:01:11 INFO - Running setup.py install for manifestparser 19:01:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:01:11 INFO - Running setup.py install for mozcrash 19:01:11 INFO - Running setup.py install for mozdebug 19:01:11 INFO - Running setup.py install for mozdevice 19:01:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:01:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:01:11 INFO - Running setup.py install for mozfile 19:01:12 INFO - Running setup.py install for mozhttpd 19:01:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:01:12 INFO - Running setup.py install for mozinfo 19:01:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:01:12 INFO - Running setup.py install for mozInstall 19:01:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:01:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:01:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:01:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:01:12 INFO - Running setup.py install for mozleak 19:01:12 INFO - Running setup.py install for mozlog 19:01:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:01:12 INFO - Running setup.py install for moznetwork 19:01:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:01:12 INFO - Running setup.py install for mozprocess 19:01:13 INFO - Running setup.py install for mozprofile 19:01:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:01:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:01:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:01:13 INFO - Running setup.py install for mozrunner 19:01:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:01:13 INFO - Running setup.py install for mozscreenshot 19:01:13 INFO - Running setup.py install for moztest 19:01:13 INFO - Running setup.py install for mozversion 19:01:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:01:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:01:13 INFO - Cleaning up... 19:01:13 INFO - Return code: 0 19:01:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01: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')]} 19:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01: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')]} 19:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e3d80a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, '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.fE9231BAdz/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.dPsc0mCVUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:01:13 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 19:01:13 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 19:01:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:01:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:13 INFO - 'HOME': '/Users/cltbld', 19:01:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:13 INFO - 'LOGNAME': 'cltbld', 19:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:13 INFO - 'MOZ_NO_REMOTE': '1', 19:01:13 INFO - 'NO_EM_RESTART': '1', 19:01:13 INFO - 'PAGER': '/bin/cat', 19:01:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:13 INFO - 'PWD': '/builds/slave/test', 19:01:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:13 INFO - 'SHELL': '/bin/bash', 19:01:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:01:13 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:01:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:13 INFO - 'USER': 'cltbld', 19:01:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:13 INFO - 'XPC_FLAGS': '0x0', 19:01:13 INFO - 'XPC_SERVICE_NAME': '0', 19:01:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:01:14 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-gF7EFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:01:14 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)) 19:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:01:14 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-DvQH7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:01:14 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)) 19:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:01:14 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eDNdTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:01:14 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)) 19:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:01:14 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-b3AOjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:01:14 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)) 19:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:01:14 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-tV161j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:01:14 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)) 19:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:01:14 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-m4kQ00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:01:14 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)) 19:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:01:14 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-JVt69w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:01:14 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)) 19:01:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:01:14 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-l4OJye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:01:15 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)) 19:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:01:15 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-iMoZ6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:01:15 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)) 19:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:01:15 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-xp0M1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:01:15 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)) 19:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:01:15 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-H6Kbko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:01:15 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)) 19:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:01:15 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-tRvYhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:01:15 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)) 19:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:01:15 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-augMvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:01:15 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)) 19:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:01:15 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-CRKGB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:01:15 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)) 19:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:15 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-kdhXgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:01:15 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)) 19:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:01:15 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-a7LPv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:01:15 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)) 19:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:01:15 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-IoLWJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:01:16 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)) 19:01:16 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)) 19:01:16 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)) 19:01:16 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)) 19:01:16 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)) 19:01:16 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)) 19:01:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01:18 INFO - Downloading blessings-1.6.tar.gz 19:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:01:18 INFO - Installing collected packages: blessings 19:01:18 INFO - Running setup.py install for blessings 19:01:18 INFO - Successfully installed blessings 19:01:18 INFO - Cleaning up... 19:01:19 INFO - Return code: 0 19:01:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:01:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:01:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:01:19 INFO - Reading from file tmpfile_stdout 19:01:19 INFO - Current package versions: 19:01:19 INFO - blessings == 1.6 19:01:19 INFO - blobuploader == 1.2.4 19:01:19 INFO - docopt == 0.6.1 19:01:19 INFO - manifestparser == 1.1 19:01:19 INFO - mozInstall == 1.12 19:01:19 INFO - mozcrash == 0.17 19:01:19 INFO - mozdebug == 0.1 19:01:19 INFO - mozdevice == 0.48 19:01:19 INFO - mozfile == 1.2 19:01:19 INFO - mozhttpd == 0.7 19:01:19 INFO - mozinfo == 0.9 19:01:19 INFO - mozleak == 0.1 19:01:19 INFO - mozlog == 3.2 19:01:19 INFO - moznetwork == 0.27 19:01:19 INFO - mozprocess == 0.22 19:01:19 INFO - mozprofile == 0.28 19:01:19 INFO - mozrunner == 6.11 19:01:19 INFO - mozscreenshot == 0.1 19:01:19 INFO - mozsystemmonitor == 0.3 19:01:19 INFO - moztest == 0.7 19:01:19 INFO - mozversion == 1.4 19:01:19 INFO - psutil == 3.1.1 19:01:19 INFO - requests == 1.2.3 19:01:19 INFO - wsgiref == 0.1.2 19:01:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:01:19 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')]} 19:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:19 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')]} 19:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e3d80a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, '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.fE9231BAdz/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.dPsc0mCVUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:01:19 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 19:01:19 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 19:01:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:01:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:19 INFO - 'HOME': '/Users/cltbld', 19:01:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:19 INFO - 'LOGNAME': 'cltbld', 19:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:19 INFO - 'MOZ_NO_REMOTE': '1', 19:01:19 INFO - 'NO_EM_RESTART': '1', 19:01:19 INFO - 'PAGER': '/bin/cat', 19:01:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:19 INFO - 'PWD': '/builds/slave/test', 19:01:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:19 INFO - 'SHELL': '/bin/bash', 19:01:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:01:19 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:01:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:19 INFO - 'USER': 'cltbld', 19:01:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:19 INFO - 'XPC_FLAGS': '0x0', 19:01:19 INFO - 'XPC_SERVICE_NAME': '0', 19:01:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:19 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)) 19:01:19 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)) 19:01:19 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)) 19:01:19 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)) 19:01:19 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)) 19:01:19 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)) 19:01:19 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)) 19:01:19 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)) 19:01:19 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)) 19:01:19 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)) 19:01:19 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)) 19:01:19 INFO - Cleaning up... 19:01:19 INFO - Return code: 0 19:01:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:01:19 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')]} 19:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:19 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')]} 19:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e3d80a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, '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.fE9231BAdz/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.dPsc0mCVUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:01:19 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 19:01:19 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 19:01:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:01:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:19 INFO - 'HOME': '/Users/cltbld', 19:01:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:19 INFO - 'LOGNAME': 'cltbld', 19:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:19 INFO - 'MOZ_NO_REMOTE': '1', 19:01:19 INFO - 'NO_EM_RESTART': '1', 19:01:19 INFO - 'PAGER': '/bin/cat', 19:01:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:19 INFO - 'PWD': '/builds/slave/test', 19:01:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:19 INFO - 'SHELL': '/bin/bash', 19:01:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:01:19 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:01:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:19 INFO - 'USER': 'cltbld', 19:01:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:19 INFO - 'XPC_FLAGS': '0x0', 19:01:19 INFO - 'XPC_SERVICE_NAME': '0', 19:01:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:19 INFO - Downloading/unpacking jsonschema 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01: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 19:01:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:01:22 INFO - Downloading/unpacking functools32 (from jsonschema) 19:01:22 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 19:01:22 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 19:01:22 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 19:01:22 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 19:01:22 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 19:01:22 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 19:01:22 INFO - Downloading functools32-3.2.3-2.tar.gz 19:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:01:22 INFO - warning: no files found matching '*.txt' 19:01:22 INFO - no previously-included directories found matching 'build' 19:01:22 INFO - no previously-included directories found matching 'dist' 19:01:22 INFO - no previously-included directories found matching '.git*' 19:01:22 INFO - Installing collected packages: jsonschema, functools32 19:01:22 INFO - Running setup.py install for functools32 19:01:22 INFO - warning: no files found matching '*.txt' 19:01:22 INFO - no previously-included directories found matching 'build' 19:01:22 INFO - no previously-included directories found matching 'dist' 19:01:22 INFO - no previously-included directories found matching '.git*' 19:01:22 INFO - Successfully installed jsonschema functools32 19:01:22 INFO - Cleaning up... 19:01:23 INFO - Return code: 0 19:01:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:01:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:01:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:01:23 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 0x100c81a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c87570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85e3d80a90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, '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.fE9231BAdz/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.dPsc0mCVUS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 19:01:23 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 19:01:23 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 19:01:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:01:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:01:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:01:23 INFO - 'HOME': '/Users/cltbld', 19:01:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:01:23 INFO - 'LOGNAME': 'cltbld', 19:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:23 INFO - 'MOZ_NO_REMOTE': '1', 19:01:23 INFO - 'NO_EM_RESTART': '1', 19:01:23 INFO - 'PAGER': '/bin/cat', 19:01:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:23 INFO - 'PWD': '/builds/slave/test', 19:01:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:01:23 INFO - 'SHELL': '/bin/bash', 19:01:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:01:23 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:01:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:01:23 INFO - 'USER': 'cltbld', 19:01:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:01:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:23 INFO - 'XPC_FLAGS': '0x0', 19:01:23 INFO - 'XPC_SERVICE_NAME': '0', 19:01:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:01:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:01:23 INFO - Downloading/unpacking flake8 19:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:01:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:01:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:01:25 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 19:01:25 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 19:01:25 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 19:01:25 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 19:01:25 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 19:01:25 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 19:01:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:01:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 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 19:01:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:01:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:01:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:01:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:01:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:01:26 INFO - no previously-included directories found matching 'docs/_build' 19:01:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:01:27 INFO - Running setup.py install for pep8 19:01:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:01:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:01:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:01:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:01:27 INFO - no previously-included directories found matching 'docs/_build' 19:01:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:01:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:01:27 INFO - Cleaning up... 19:01:27 INFO - Return code: 0 19:01:27 INFO - Running post-action listener: _resource_record_post_action 19:01:27 INFO - Running post-action listener: _start_resource_monitoring 19:01:27 INFO - Starting resource monitoring. 19:01:27 INFO - ##### 19:01:27 INFO - ##### Finished create-virtualenv step (success) 19:01:27 INFO - ##### 19:01:27 INFO - ##### 19:01:27 INFO - ##### Running install step. 19:01:27 INFO - ##### 19:01:27 INFO - Running pre-action listener: _resource_record_pre_action 19:01:27 INFO - Running main action method: install 19:01:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:01:27 INFO - Reading from file tmpfile_stdout 19:01:27 INFO - Detecting whether we're running mozinstall >=1.0... 19:01:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:01:27 INFO - Reading from file tmpfile_stdout 19:01:27 INFO - Output received: 19:01:27 INFO - Usage: mozinstall [options] installer 19:01:27 INFO - Options: 19:01:27 INFO - -h, --help show this help message and exit 19:01:27 INFO - -d DEST, --destination=DEST 19:01:27 INFO - Directory to install application into. [default: 19:01:27 INFO - "/builds/slave/test"] 19:01:27 INFO - --app=APP Application being installed. [default: firefox] 19:01:27 INFO - mkdir: /builds/slave/test/build/application 19:01:27 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'] 19:01:27 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 19:02:01 INFO - Reading from file tmpfile_stdout 19:02:01 INFO - Output received: 19:02:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:02:01 INFO - Running post-action listener: _resource_record_post_action 19:02:01 INFO - ##### 19:02:01 INFO - ##### Finished install step (success) 19:02:01 INFO - ##### 19:02:01 INFO - ##### 19:02:01 INFO - ##### Running run-tests step. 19:02:01 INFO - ##### 19:02:01 INFO - Running pre-action listener: _resource_record_pre_action 19:02:01 INFO - Running main action method: run_tests 19:02:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:02:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:02:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:02:01 INFO - 2016-06-07 19:02:01.133 screenresolution[1952:13604] starting screenresolution argv=screenresolution get 19:02:01 INFO - 2016-06-07 19:02:01.136 screenresolution[1952:13604] Display 0: 1600x1200x32@60 19:02:01 INFO - 2016-06-07 19:02:01.141 screenresolution[1953:13606] starting screenresolution argv=screenresolution list 19:02:01 INFO - Available Modes on Display 0 19:02:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:02:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:02:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:02:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:02:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:02:01 INFO - Intel Iris: 19:02:01 INFO - Chipset Model: Intel Iris 19:02:01 INFO - Type: GPU 19:02:01 INFO - Bus: Built-In 19:02:01 INFO - VRAM (Dynamic, Max): 1536 MB 19:02:01 INFO - Vendor: Intel (0x8086) 19:02:01 INFO - Device ID: 0x0a2e 19:02:01 INFO - Revision ID: 0x0009 19:02:01 INFO - Displays: 19:02:01 INFO - Display: 19:02:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:02:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:02:01 INFO - Display Serial Number: 1600x1200 60 19:02:01 INFO - Main Display: Yes 19:02:01 INFO - Mirror: Off 19:02:01 INFO - Online: Yes 19:02:01 INFO - Rotation: Supported 19:02:01 INFO - Return code: 0 19:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:02:01 INFO - Python 2.7.3 19:02:01 INFO - Return code: 0 19:02:01 INFO - grabbing minidump binary from tooltool 19:02:01 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')]} 19:02:01 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 0x100b25d30>, '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 0x100949db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b1cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:02:01 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 19:02:01 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 19:02:01 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 19:02:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:02:01 INFO - Return code: 0 19:02:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:02:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:02:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:02:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:02:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:02:02 INFO - Return code: 0 19:02:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 19:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --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 19:02:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fE9231BAdz/Render', 19:02:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:02 INFO - 'HOME': '/Users/cltbld', 19:02:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:02 INFO - 'LOGNAME': 'cltbld', 19:02:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:02:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:02 INFO - 'MOZ_NO_REMOTE': '1', 19:02:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:02:02 INFO - 'NO_EM_RESTART': '1', 19:02:02 INFO - 'PAGER': '/bin/cat', 19:02:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:02 INFO - 'PWD': '/builds/slave/test', 19:02:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:02:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:02 INFO - 'SHELL': '/bin/bash', 19:02:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dPsc0mCVUS/Listeners', 19:02:02 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 19:02:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:02 INFO - 'USER': 'cltbld', 19:02:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:02 INFO - 'XPC_FLAGS': '0x0', 19:02:02 INFO - 'XPC_SERVICE_NAME': '0', 19:02:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 19:02:02 INFO - mozversion application_buildid: 20160607155849 19:02:02 INFO - mozversion application_changeset: cad514ad49c199e823a92e8c8d27e16c22c3cac7 19:02:02 INFO - mozversion application_display_name: Nightly 19:02:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:02:02 INFO - mozversion application_name: Firefox 19:02:02 INFO - mozversion application_remotingname: firefox 19:02:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:02:02 INFO - mozversion application_vendor: Mozilla 19:02:02 INFO - mozversion application_version: 49.0a1 19:02:02 INFO - mozversion platform_buildid: 20160607155849 19:02:02 INFO - mozversion platform_changeset: cad514ad49c199e823a92e8c8d27e16c22c3cac7 19:02:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:02:02 INFO - mozversion platform_version: 49.0a1 19:02:02 INFO - using testdate: 1465351322 19:02:02 INFO - actual date: 1465351322 19:02:02 INFO - starting webserver on 'localhost:49523' 19:02:02 INFO - SUITE-START | Running 2 tests 19:02:02 INFO - TEST-START | dromaeo_css 19:02:02 INFO - operating with platform_type : mac_ 19:02:02 INFO - Initialising browser for dromaeo_css test... 19:02:02 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpei09Oo/profile http://localhost:49523/getInfo.html) 19:02:04 INFO - PROCESS | 1971 | __metrics Screen width/height:1600/1200 19:02:04 INFO - PROCESS | 1971 | colorDepth:24 19:02:04 INFO - PROCESS | 1971 | Browser inner width/height: 1024/689 19:02:04 INFO - PROCESS | 1971 | __metrics 19:02:05 INFO - PROCESS | 1971 | 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" 19:02:05 ERROR - PROCESS | 1971 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:02:05 INFO - TEST-INFO | 1971: exit 0 19:02:05 INFO - Browser initialized. 19:02:05 INFO - Running cycle 1/1 for dromaeo_css test... 19:02:05 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpei09Oo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:02:17 INFO - PROCESS | 1973 | RSS: Main: 241635328 19:02:17 INFO - PROCESS | 1973 | 19:03:09 INFO - PROCESS | 1973 | 1465351389791 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] 19:05:19 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49523/tests/dromaeo/cssquery-ext.html) 19:05:21 INFO - PROCESS | 1973 | RSS: Main: 362737664 19:05:21 INFO - PROCESS | 1973 | 19:08:24 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-ext.html (next: http://localhost:49523/tests/dromaeo/cssquery-jquery.html) 19:08:24 INFO - PROCESS | 1973 | RSS: Main: 373583872 19:08:24 INFO - PROCESS | 1973 | 19:11:27 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49523/tests/dromaeo/cssquery-mootools.html) 19:11:27 INFO - PROCESS | 1973 | RSS: Main: 374300672 19:11:27 INFO - PROCESS | 1973 | 19:14:30 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49523/tests/dromaeo/cssquery-prototype.html) 19:14:30 INFO - PROCESS | 1973 | RSS: Main: 370900992 19:14:30 INFO - PROCESS | 1973 | 19:17:33 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49523/tests/dromaeo/cssquery-yui.html) 19:17:34 INFO - PROCESS | 1973 | RSS: Main: 339202048 19:17:34 INFO - PROCESS | 1973 | 19:20:37 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:49523/tests/dromaeo/cssquery-yui.html (next: http://localhost:49523/tests/dromaeo/cssquery-dojo.html) 19:20:37 INFO - PROCESS | 1973 | RSS: Main: 416673792 19:20:37 INFO - PROCESS | 1973 | 19:20:37 INFO - PROCESS | 1973 | __start_tp_report 19:20:37 INFO - PROCESS | 1973 | _x_x_mozilla_page_load 19:20:37 INFO - PROCESS | 1973 | _x_x_mozilla_page_load_details 19:20:37 INFO - PROCESS | 1973 | |i|pagename|runs| 19:20:37 INFO - PROCESS | 1973 | |0;dojo.html;3222.6777322267776;3393.6267010628826;3409.1306716787217;3414.334204830056;3451.0854623524756;5485.506304432601;5519.862003449915;5574.247476590659;5609.635373700707;5629.7466614002315;5392.811251606195;5542.944570554293;5650.51970582501;5660;5698.487136157745;4862.683925544846;4878.560929516343;4936.703797772145;4974.900501989958;5113.002964421939;5012.874678133039;5055.51972562606;5056.747162641853;5097.974510127444;5118.360204974379;4192.979035104805;5040.143175660147;5051.06555287273;5133.3326667533165;5196.532312091911;5102.515261050195;5116.309298244727;5125.154349532323;5129.871753206163;5140.794368225266;5054.974725126351;5068.087744206078;5087.56755675768;5107.463716309809;5121.052605268037;5468.699221542814;5491;5507.7246137693355;5578.944210557883;5601.663900166003;6097.021124203537;6109.265503518817;6133.846653833645;6152.138700581922;6165.691715414257;5689.772409103631;6200.472959798422;6237.812865613993;6262.780802671885;6266.624002559861;5169.431362550116;5684.033714268584;5686.886262274731;5701.800436984686;5715.114157305624;6285.96857015712;6290.842728931813;6294.748210071546;6339.334369891188;6342.985122380397;5864.354920958691;6061.269364322841;6064.151018857364;6074.726637301332;6081.939180608226;5810.244668193108;6054.753608760578;6118.923762351576;6149.169862068653;6153.84615384619;6107.206063211754;6113.052476866093;6122.479589234876;6126.509879209652;6128.325884152829;5972.701364931736;6060.606060606135;6138.7237574309265;6152.630842149485;6182.907256391158;4224.725392849455;4744.122337367598;4789.880252993703;4816.927746083811;4819.662623616383;4308.482982042175;4314.115606300701;4341.34879768031;4341.804618792162;4390.319500477431;5648.15277708347;5667;5675.886482270332;5696.857578560486;5731.140328950691;6096.146539484475;6127.448529632355;6229.750809967551;6254;6256;4665.673402861835;4725.9527404726205;5658.886822263532;5674.347450043216;5684.9431505684415;5299.2845965794895;5326.893462130735;5331.893362132736;5334.466553344634;5336.8398948030845;6216.409441103088;6234.064890266496;6240.500759939194;6271.843203919939;6281.937180628227;6134.436587033385;6203.193584833943;6228.56400051992;6254.593451425642;6256.874862502816;6263.154474146114;6275.152854364785;6284.277308109626;6300.369963003663;6305.432511074026;5989.550783691154;6062.727177277121;6078.665673388006;6100.450959413674;6108.602940808833;5289.550388217045;6083.8744832206185;6211.099390588249;6222.191115155182;6235.220597425322;6067.150598916067;6073.331933487401;6096.969515152396;6111.663858487826;6124.38756124384;5759.1649210865025;5947.553933455095;5988.071848863433;6031.276246850406;6086.6348019119;5850;5877.059670452878;5878.353381128158;5892.322382926019;5931.436513531207;6066.400744979152;6084.777573676266;6091.839318428981;6111.8820597758495;6123.2958209804665;5739.971300143639;5743.62666426681;5784.074548072456;5840.795572154954;5888.911666325094 19:20:37 INFO - PROCESS | 1973 | |1;ext.html;474.0116856354501;490.98281560145404;501.3532543019505;507.49250749250774;515.99381206647;30264.092077237663;40842.38736418956;41612.583874161246;41866.138740576345;42499.36250956238;41272;41828.79085604568;43872.56127438725;43908.560914390844;43981.34027989575;8624.439411438269;9454.810903781903;9673.419594824298;9678.37090589113;9682.322237443383;11421.828672569918;11657.125715571323;11659.475323610415;11890.048796096335;11893.762124757499;11588.536458541648;11652.359120248382;11665.766684666301;11669.474873630665;11683.941580292087;11669.241499302518;11791.941040294829;11793.469293881795;11797.823032654516;11806.46870890812;11453.198276120675;11534.134939879543;11535.653930382103;11537.653870383901;11553.19127661064;32698;40439.393409098884;40442.19115617671;40466.190676186605;40522.39216411756;3496.527968724215;3506.4740288956605;3508.1405055761247;3550.5029295898594;3650.9634903651154;31171.532427013386;31364.112802595457;31895.681043189503;32274.031779046665;32523;3270.8201048942296;3299.071700554984;3303.7687361884678;3326.2515933914674;3386.0857568456627;491784;492407;492461.53769231285;492981;495660;59427;59824;60235.096473552934;60333.79332413327;60351.698241508515;62118.68940655268;62171.37828621746;62291;62330.688346557974;62424.687876560325;15971.520854374387;16492.25784839685;16519.504414867573;16532.586685332964;16550.33798648065;12714;12806.67983300425;12806.80789788154;12809.487620495262;12824.615261542169;15951.680966380607;16153.757693634605;16222.837771622133;16224.756628650579;16231.18844057816;5564.944350556523;5796.594238403356;6223.222097237845;6272.247330320391;6275.780347687809;939.8026414453042;1090.9781804364027;1113.980707652505;1127.3968426891574;1175.5121624525898;26747.33131671724;30138;30735.538966915516;30974;31190.844045779624;1075.3440401355167;1106.4633652678444;1150.7238262816863;1181.7754626620915;1202.9939850300693;15666.373345066298;15688.921555392379;15691.293891774669;15900.284487198103;16126.67746645084;662846;666089;670509.6474517597;670569;675436;59369.10946335809;59403;59431;59433.10850337248;59619.50951226254;1267.283984548727;1304.243538747524;1321.3855557165964;1416.214001229307;1426.493594773861;26974.32564185911;28971.85514072416;31445;31532.369352613277;31817.5227371585;49430.75284623554;50798.746006269736;50815;50819;50833.74583127061;552789;570290;570879;572186;573829;34629.13427164341;36018.45972310365;40774;41115.50518205867;41185.38221926614;45977;47972.7601361991;48023.5197648019;49134.508654914425;49138.50861491483;38413.615863841005;39712.80143599264;40015.59984400119;40541.59458405378;40769.59230407658;38590.614093858705;38781;38857.417138743505;39587.01032474096;39692;32574.837125814218;38131.80934095312;38484.80757596306;38713;38805.611943881326;33923.49114763231;34472.82763586166;35475.64524354723;35476.46785298274;35717.82141089278;25175.622365664894;25593;25721.228363149134;25728.485430291665;26365.60451593266 19:20:37 INFO - PROCESS | 1973 | |2;jquery.html;379.90882188274816;383.5033631447277;386.1389102301866;398.6272834899371;404.88663174311205;20234.494137646565;21981.780182198174;22340.55318893621;22434.43913902153;22439;20769.96150192488;20927.895360523176;21466.248681296158;21532.56934861302;21538;5537.36320323163;5777.768889244425;5875.059990401536;5878.794242201524;5963.01610234313;4124.381342798574;4352.12957408518;5795.913061304083;6178.227721534809;6509.218893732735;6412.775552855651;6486.772962946297;6499.675016249193;6500.9674951625175;6514.967425162868;6279.120923070774;6427;6492.383223593751;6501.349865013508;6516.413522782948;3683.208110256281;3843.730938834283;3900.31744444721;5590.580706447033;5596;26249;26581.335466613295;26606.6009009865;26905.461890762075;26910.865445672647;2468.987894671159;2811.973629625172;3127.218195451137;3137.7176054155007;3234.498652708834;7093.503454758169;7099.680514376813;11568.248063875906;13475.315585551805;15959.202039898075;1802.1079565614973;1829.3414370826492;1848.3992702371784;1903.2101677803832;1919.89440580768;112305;115877;121312.39343803313;123909.38045309715;124098.2770516895;10834.187435942336;11165.385903775208;11226.775464490664;11238.943805280922;11415.543378264776;14168.433262669378;14240;14254.643633909236;14255.572332830032;14257.64355891111;8774.517401542977;8808.603612837438;8866.601002954883;8884.333674974403;9001.459912405277;8835;8854.335924805537;8861.556922153866;8977.640894364153;8995.23540499065;5659.3208814941645;6654.600723956578;6798.422134118655;6810.148731408574;6859.862802743917;1653.2477722636172;1710.1107424139377;1762.8237176282269;1807.3945228348387;1818.2454281473306;877.3244601656688;948.9240860731124;1041.9010194031555;1046.6650671784955;1106.1537923488538;21252.468688282916;21985.890070549543;21995.780042199374;22007.009684564233;22086.779132208794;817.6157206113118;971.6793458158791;1017.211660962745;1020.1940467031111;1109.8890110988837;9334.766630834147;11851.288922664495;12162;12193.085518586142;12226.633201003984;562493;571069;582306;584247;586246;102437;103453;106872.17533895982;112707.43646281716;115412;873.724776695339;1014.9797004059877;1016.9796604067877;1033.6847261585137;1038.958441662325;19206;20284.391468255977;20419.8979005104;20604.484887877323;20637.69043464319;32167;35326.47010294796;36403.63596364002;41862;51064.48935510746;66417.33582664111;66634.33365666475;66946.99579506213;67050.63594912298;68030;7024.754133605299;7479.289467500579;8176.264136227768;8208.917910820815;8427.283680887193;8780.473171609468;8824.602892869709;8945.865812012695;11814;13370.465181392636;7972.481788683717;8053.718119865776;8093.109757926505;8145.959270203611;8242.381821363302;7656.96630954814;8089.433739638169;8092.069412017693;8271.296939760188;8285.917140828515;7782.727604533814;7841.059072911287;8257.545834978893;8465.98408191021;8557.400981931205;12601.495940162291;12751.298678572397;12806.935965320114;12974.351282435651;13136.277504736949;11961.700957476132;12011.579594714143;12029;12070.75858482843;12119.939400302943 19:20:37 INFO - PROCESS | 1973 | |3;mootools.html;1406.9859301406982;1563.86707129894;1586.6667999720057;1832.761740973673;1982.1476764991057;7069.540479868811;8534.57327133644;8749;8754.343424243183;8903.866442003358;8488.236058754726;8734.301255899529;8742.344324175681;8743.431676940994;8748.300135989122;2103.4110449074205;2157.2557467673705;2313.063209400187;2328.2549584133085;2358.6462030695448;2190.080166330145;2219.511707424372;2377.3818807110188;2418.7218469876016;2418.7944024757917;2328.976710232893;2355.564220619191;2366.2782851230368;2368.834181607288;2370.5495955768433;2070.7722150563427;2257.6387777955447;2310.953780924372;2311.0293676655847;2311.687922130516;1955.9804401955942;2343.414146463384;2363.3855197648563;2367.5856725073045;2367.7277113132036;8679.956600217021;8752.168543988311;8752.387332886701;8754.3871928965;8761.7809554761;5139.17773156297;5313.256144139785;5315.202719592054;5315.893682126374;5316.149416093406;3400.8129552874584;3470.0804286864;3624.5106910567265;3629.0927268182954;3631.7094632429607;1063.2291588598282;1075.8870318616512;1104.7293413113757;1120.0678829019957;1125.9774804503863;11697.3566453845;11710.121740869468;11715.297082175011;11721.531138754439;11736.471858766376;3831.8084095795098;4188.560201178893;4661.091087238024;4787.042591481718;4798.904021919542;9770.902290977141;9789.902100979041;9814;9817.312788104908;9828.017198280115;5314.229436731653;5322.893542129135;5349.839504814861;5356.4107948125675;5358.169483730028;4392.033752574429;4494.258447356215;4743.786529606176;4834.854954351375;4841.15279826029;3903.8828835134423;3949.7827619481204;4020.6984476164403;4042.8989275268355;4069.064115253508;1288.4910460368103;1297.228149251213;1302.635262126606;1308.2346827106248;1309.4500309869877;913.1234015345343;920.7375897869075;931.1340453378423;938.441627231796;976.0971101731012;5902.1146827976145;5908.527317814564;5996.880062398727;6080;6153.384661533811;427.20753003179277;445.944256967879;902.4449963272672;961.7257134297084;1124.791913496006;3752.3433399155037;3961.8811435656976;3999.1601763629383;4033.576474470197;4133.586641335903;10293.588256469808;10451.477426128664;10651.786964260671;10661.466926653637;10789.730256743644;10496.68509944703;10581.99471050249;10705.411202383788;10735.94632026835;10748.247622666338;8013.959930200312;8054.758357249292;8136.308413784776;8181.550014749127;8202.220789025152;5775.8556036099435;6019.217501724747;6058.182145410313;6078.088286757022;6095.664738439428;9764.365316254421;9949.850752238577;10026.799464010825;10039.498025098861;10040.895501494684;9227.907720922973;9263.16631503168;9744.951275243578;10236.692899213036;10296.639617613348;6993.440524758109;7009.12385951756;7145.714171433292;7206.279372062962;7206.963965180141;7823.84352312962;7937.801554961172;7977.880331794911;8239.299659529008;8248.505089694638;7323.084614423197;7446.664900079618;7464.552126872405;7593.544387336557;7612.3910087194;7203.099612548432;7237.493375463667;7245.927540724525;7320.194778574246;7374.926250737424;6941.167059952838;6943.305669433016;7017.684204210721;7020.894686579808;7044.6125463100025;4228;4784.497627749036;4868.075065737498;4972.65191436596;5093.0577843099145;4813.205821039489;5066.746662666926;5075.137226671401;5129.256257842667;5203.453637368022 19:20:37 INFO - PROCESS | 1973 | |4;prototype.html;305.1075603858713;310.1440025529538;314.2050611951762;315.9935605097765;321.74903575211346;5269.446708095652;5301.8409447716585;5310.336207974004;5311.1502159654465;5322.893542129155;5212.322398088253;5217.260938007548;5228.29418028566;5243.842684719464;5246.842594722164;3191.1862475068824;3779.225258821935;3812.7140464465137;3917.373220284755;4001.339778936472;3922.3724204127348;4009.6391324780766;4013.618706222904;4028.0262671291002;4039.4142849286836;3036.5596988436664;3269.2019389335887;3523.4362501999685;3619.457081437792;4018.4976877890263;2765.9170224893082;3283.7029373397613;3791.7535360201637;3899.259140763246;3934.4688467057013;3460.688538031564;3480.94778578319;3493.7205023598053;3516.595591506984;3531.346700860349;4577.924187815861;4988.950110498885;5018.799248030111;5040.395152581677;5047.192449208146;4279.850205242833;4551.249043907785;4596.218642830727;4618.49196588375;4667.6732628716;4917.532834380728;5079.517445842639;5479.904019196177;5491.176323551459;5494.230807686927;3881.514810648669;3908.9413658795143;3914.9804250978564;3916.5104361954754;3919.941200881989;5463.0606134822365;5775.509081728016;5819.912701309441;5823.388544202882;5824.621399609055;3324.1523411529906;3900.531936167678;3946.408038794204;3956.3274243378696;3965.6629186519467;3476.8873960332958;3972.205558888234;3999.140399714147;4023.939640905389;4035.1324465240114;4301.354666879846;4430.490493593214;4606.286025666027;4608.400907881954;4638.002829391629;4937.802487900443;5505.834824955257;5564.86087847799;5607.663540187602;5615.466530679579;1224.5469176404788;1259.9118061735774;1337.2511393619604;1347.6698208938872;1393.1501783911806;744.5123444144094;791.7110254756972;793.3018943329948;793.9364850811921;804.4409135650784;4775.785089670973;5002.949970500321;5092.439831618519;5122.334096567497;5124.718140502234;5450.727463626803;5675.0636145035605;5700.914486282709;5726.909677967596;5803.47768700819;4307.698461107692;4348.847790327323;4776.832810851603;5122.487751224848;5131.871703207375;2335.66132910727;2714.3214196450886;2940.9705902940696;2971.7622590193164;2984.925376865552;4897.706137631753;5048.495150484922;5203.60972927032;5409.810656626999;5868.713520428573;5285.603579731535;5331.760070796823;5750.568707346965;5760.769569217268;5771.278590176228;5506.68719137963;5719.113537401709;5835.708214589338;5879.794207202727;5891.3224979127945;4632.143053535106;5236.298203083937;5615.848751005952;5627.296587926509;5658.801941932013;4599.356090147315;4609.838655647036;5259.289995850511;5600.4959553640365;5626;5382.461753824587;5859.9121013183985;5903.940960590339;5906.40935906406;5916.455923952742;3547.467879818048;3816.4847745555107;3892.8637497687446;3901.848954558469;3923.2737595457907;4690.695104818176;4694.953050469451;5621.213030175697;5644.520215781705;5645.548356131581;4656.068786242698;4774.976125119352;5401.027814993339;5410.756515956713;5469.7573994662;4427.46870375557;5061.924071139009;5444.26502422168;5461.4980880258245;5520.371490410258;4955.975220123876;5214.42641309448;5255;5371.060064488777;5384.542313903362;5026.52248036436;5309.309789727311;5397.730113494388;5423.105187643994;5435.374931882884;4555.908881822279;4938.30863679078;5338.919916201337;5344;5350.5922334948655 19:20:37 INFO - PROCESS | 1973 | |5;yui.html;793.5714714054411;828.3000864269694;829.9253067223952;835.3651225068945;852.3011130872685;14789.408423663053;15208.011479253868;15212.923935380322;15243.618909527268;16251.187440627951;15769.526914192593;16201.513954581353;16202.918985405086;16223.83776162241;16237.106959117244;898.1063841477738;900.5002223765807;913.5386629751999;914.5518695839057;914.9130832570895;701.3722718167237;725.7205975699368;727.5380133615166;729.6205972894119;907.2152588011355;761.0942977856333;911.8221946720392;943.6131186213654;945.6359301668873;951.3103000324778;935.2751617496494;935.2985261054209;935.5789894547447;937.4422218779815;938.5870217104521;551.6689986008363;696.4532841719226;698.1064237775655;698.9615571143636;699.7935608995308;12348.197367171195;12534.623961281084;12545.1218414711;14498.855011449856;14960.625984350478;13480.93259533696;13500.392482338319;13584.932075339659;13927;13996.72006559863;11017.779644407146;11699.947004769612;11704.531818727326;11705.414729263588;11740.70648233801;3730.2725968436166;3737.9252414951548;3750.062484378905;3878.1855810279876;3886.9805650971566;51976.480235197545;75023.62488187633;77984.61007694925;80250;81884.59057704733;10568.15454763617;10642.680719578426;10707.464626768631;10715.785684286271;10808;20623.071961761758;20918.477038073866;20941.581168376546;20959.37121886346;20999.79000210009;7664.31021208094;7706.306432421109;7753.302202801775;7845.411594130463;7857.489263197874;4373.222695894293;4469;4482.596566309048;4539.182947069493;4539.750313732777;3359.3113411750533;3470.8611655533496;3471.09751464621;3471.1495683557187;3481.47777833327;643.3052303512195;652.083822229768;655.1712084213474;663.8307231655897;678.7285085965653;534.3347532322283;615.5967841064111;658.4534836086037;685.9451243900476;688.6659969914588;9218.539073046455;9234.815303694022;9254.444733316022;9354.71935841926;9393.067188115827;590.8109404990362;667.8279619268153;687.9504437429193;690.433844247712;693.5145398221265;3607.494950706903;4358.782060896942;4376.299792033323;4417.929380518664;4535.160995215896;81965;84139;87822;87886;87956.56021719851;76916.61541692256;77221;77965;78000;78620.21379786129;67165;67454.65090698158;69239.26901827393;70198;70620.94068588976;8653.913460865562;8711.869321960048;8791.692290770048;8798.912010880065;8800.955995219983;45010.54989450194;45151.77424112859;45170.322445164005;45456;45523.77238113788;56668;57421.42578574161;57545.56136096631;57738.42261577499;58315.708421457624;10118.645847395306;11386.829197562209;11574.247673901169;11669.708257293636;11763.059375109093;10173.949130254301;12811.231326120462;12980.22118672845;13068.673283167996;13090;11581.88418115808;11871.584494542649;11940.52237910474;11955.163138580216;12346.938265308616;11712.648620541397;11840.171188016757;11923.403829808301;12028.398580071136;12255.509779608716;9617.23062155012;9617.663381781604;11806.232594881305;12067.75864482723;12150.57472988441;9473.573689183866;9735.22118230554;9735.561899714388;9788.216942644714;10197.337173083726;9236.491992940453;9287;9494.240460762985;9694.321397502106;10092.34399764013 19:20:37 INFO - PROCESS | 1973 | __end_tp_report 19:20:37 INFO - PROCESS | 1973 | __start_cc_report 19:20:37 INFO - PROCESS | 1973 | _x_x_mozilla_cycle_collect,1185 19:20:37 INFO - PROCESS | 1973 | __end_cc_report 19:20:37 INFO - PROCESS | 1973 | __startTimestamp1465352437710__endTimestamp 19:20:37 INFO - PROCESS | 1973 | 19:20:37 INFO - PROCESS | 1973 | ------- Summary: start ------- 19:20:37 INFO - PROCESS | 1973 | Number of tests: 6 19:20:37 INFO - PROCESS | 1973 | 19:20:37 INFO - PROCESS | 1973 | [#0] dojo.html Cycles:165 Average:5632.00 Median:5840.80 stddev:668.39 (11.4%) stddev-sans-first:643.16 19:20:37 INFO - PROCESS | 1973 | Values: 3222.7 3393.6 3409.1 3414.3 3451.1 5485.5 5519.9 5574.2 5609.6 5629.7 5392.8 5542.9 5650.5 5660.0 5698.5 4862.7 4878.6 4936.7 4974.9 5113.0 5012.9 5055.5 5056.7 5098.0 5118.4 4193.0 5040.1 5051.1 5133.3 5196.5 5102.5 5116.3 5125.2 5129.9 5140.8 5055.0 5068.1 5087.6 5107.5 5121.1 5468.7 5491.0 5507.7 5578.9 5601.7 6097.0 6109.3 6133.8 6152.1 6165.7 5689.8 6200.5 6237.8 6262.8 6266.6 5169.4 5684.0 5686.9 5701.8 5715.1 6286.0 6290.8 6294.7 6339.3 6343.0 5864.4 6061.3 6064.2 6074.7 6081.9 5810.2 6054.8 6118.9 6149.2 6153.8 6107.2 6113.1 6122.5 6126.5 6128.3 5972.7 6060.6 6138.7 6152.6 6182.9 4224.7 4744.1 4789.9 4816.9 4819.7 4308.5 4314.1 4341.3 4341.8 4390.3 5648.2 5667.0 5675.9 5696.9 5731.1 6096.1 6127.4 6229.8 6254.0 6256.0 4665.7 4726.0 5658.9 5674.3 5684.9 5299.3 5326.9 5331.9 5334.5 5336.8 6216.4 6234.1 6240.5 6271.8 6281.9 6134.4 6203.2 6228.6 6254.6 6256.9 6263.2 6275.2 6284.3 6300.4 6305.4 5989.6 6062.7 6078.7 6100.5 6108.6 5289.6 6083.9 6211.1 6222.2 6235.2 6067.2 6073.3 6097.0 6111.7 6124.4 5759.2 5947.6 5988.1 6031.3 6086.6 5850.0 5877.1 5878.4 5892.3 5931.4 6066.4 6084.8 6091.8 6111.9 6123.3 5740.0 5743.6 5784.1 5840.8 5888.9 19:20:37 INFO - PROCESS | 1973 | 19:20:37 INFO - PROCESS | 1973 | [#1] ext.html Cycles:180 Average:71493.41 Median:19634.16 stddev:155233.94 (790.6%) stddev-sans-first:155577.83 19:20:37 INFO - PROCESS | 1973 | Values: 474.0 491.0 501.4 507.5 516.0 30264.1 40842.4 41612.6 41866.1 42499.4 41272.0 41828.8 43872.6 43908.6 43981.3 8624.4 9454.8 9673.4 9678.4 9682.3 11421.8 11657.1 11659.5 11890.0 11893.8 11588.5 11652.4 11665.8 11669.5 11683.9 11669.2 11791.9 11793.5 11797.8 11806.5 11453.2 11534.1 11535.7 11537.7 11553.2 32698.0 40439.4 40442.2 40466.2 40522.4 3496.5 3506.5 3508.1 3550.5 3651.0 31171.5 31364.1 31895.7 32274.0 32523.0 3270.8 3299.1 3303.8 3326.3 3386.1 491784.0 492407.0 492461.5 492981.0 495660.0 59427.0 59824.0 60235.1 60333.8 60351.7 62118.7 62171.4 62291.0 62330.7 62424.7 15971.5 16492.3 16519.5 16532.6 16550.3 12714.0 12806.7 12806.8 12809.5 12824.6 15951.7 16153.8 16222.8 16224.8 16231.2 5564.9 5796.6 6223.2 6272.2 6275.8 939.8 1091.0 1114.0 1127.4 1175.5 26747.3 30138.0 30735.5 30974.0 31190.8 1075.3 1106.5 1150.7 1181.8 1203.0 15666.4 15688.9 15691.3 15900.3 16126.7 662846.0 666089.0 670509.6 670569.0 675436.0 59369.1 59403.0 59431.0 59433.1 59619.5 1267.3 1304.2 1321.4 1416.2 1426.5 26974.3 28971.9 31445.0 31532.4 31817.5 49430.8 50798.7 50815.0 50819.0 50833.7 552789.0 570290.0 570879.0 572186.0 573829.0 34629.1 36018.5 40774.0 41115.5 41185.4 45977.0 47972.8 48023.5 49134.5 49138.5 38413.6 39712.8 40015.6 40541.6 40769.6 38590.6 38781.0 38857.4 39587.0 39692.0 32574.8 38131.8 38484.8 38713.0 38805.6 33923.5 34472.8 35475.6 35476.5 35717.8 25175.6 25593.0 25721.2 25728.5 26365.6 19:20:37 INFO - PROCESS | 1973 | 19:20:37 INFO - PROCESS | 1973 | [#2] jquery.html Cycles:180 Average:33377.74 Median:35865.05 stddev:95941.23 (267.5%) stddev-sans-first:96178.38 19:20:37 INFO - PROCESS | 1973 | Values: 379.9 383.5 386.1 398.6 404.9 20234.5 21981.8 22340.6 22434.4 22439.0 20770.0 20927.9 21466.2 21532.6 21538.0 5537.4 5777.8 5875.1 5878.8 5963.0 4124.4 4352.1 5795.9 6178.2 6509.2 6412.8 6486.8 6499.7 6501.0 6515.0 6279.1 6427.0 6492.4 6501.3 6516.4 3683.2 3843.7 3900.3 5590.6 5596.0 26249.0 26581.3 26606.6 26905.5 26910.9 2469.0 2812.0 3127.2 3137.7 3234.5 7093.5 7099.7 11568.2 13475.3 15959.2 1802.1 1829.3 1848.4 1903.2 1919.9 112305.0 115877.0 121312.4 123909.4 124098.3 10834.2 11165.4 11226.8 11238.9 11415.5 14168.4 14240.0 14254.6 14255.6 14257.6 8774.5 8808.6 8866.6 8884.3 9001.5 8835.0 8854.3 8861.6 8977.6 8995.2 5659.3 6654.6 6798.4 6810.1 6859.9 1653.2 1710.1 1762.8 1807.4 1818.2 877.3 948.9 1041.9 1046.7 1106.2 21252.5 21985.9 21995.8 22007.0 22086.8 817.6 971.7 1017.2 1020.2 1109.9 9334.8 11851.3 12162.0 12193.1 12226.6 562493.0 571069.0 582306.0 584247.0 586246.0 102437.0 103453.0 106872.2 112707.4 115412.0 873.7 1015.0 1017.0 1033.7 1039.0 19206.0 20284.4 20419.9 20604.5 20637.7 32167.0 35326.5 36403.6 41862.0 51064.5 66417.3 66634.3 66947.0 67050.6 68030.0 7024.8 7479.3 8176.3 8208.9 8427.3 8780.5 8824.6 8945.9 11814.0 13370.5 7972.5 8053.7 8093.1 8146.0 8242.4 7657.0 8089.4 8092.1 8271.3 8285.9 7782.7 7841.1 8257.5 8466.0 8557.4 12601.5 12751.3 12806.9 12974.4 13136.3 11961.7 12011.6 12029.0 12070.8 12119.9 19:20:37 INFO - PROCESS | 1973 | 19:20:37 INFO - PROCESS | 1973 | [#3] mootools.html Cycles:180 Average:5658.27 Median:4854.61 stddev:3170.87 (65.3%) stddev-sans-first:3163.67 19:20:37 INFO - PROCESS | 1973 | Values: 1407.0 1563.9 1586.7 1832.8 1982.1 7069.5 8534.6 8749.0 8754.3 8903.9 8488.2 8734.3 8742.3 8743.4 8748.3 2103.4 2157.3 2313.1 2328.3 2358.6 2190.1 2219.5 2377.4 2418.7 2418.8 2329.0 2355.6 2366.3 2368.8 2370.5 2070.8 2257.6 2311.0 2311.0 2311.7 1956.0 2343.4 2363.4 2367.6 2367.7 8680.0 8752.2 8752.4 8754.4 8761.8 5139.2 5313.3 5315.2 5315.9 5316.1 3400.8 3470.1 3624.5 3629.1 3631.7 1063.2 1075.9 1104.7 1120.1 1126.0 11697.4 11710.1 11715.3 11721.5 11736.5 3831.8 4188.6 4661.1 4787.0 4798.9 9770.9 9789.9 9814.0 9817.3 9828.0 5314.2 5322.9 5349.8 5356.4 5358.2 4392.0 4494.3 4743.8 4834.9 4841.2 3903.9 3949.8 4020.7 4042.9 4069.1 1288.5 1297.2 1302.6 1308.2 1309.5 913.1 920.7 931.1 938.4 976.1 5902.1 5908.5 5996.9 6080.0 6153.4 427.2 445.9 902.4 961.7 1124.8 3752.3 3961.9 3999.2 4033.6 4133.6 10293.6 10451.5 10651.8 10661.5 10789.7 10496.7 10582.0 10705.4 10735.9 10748.2 8014.0 8054.8 8136.3 8181.6 8202.2 5775.9 6019.2 6058.2 6078.1 6095.7 9764.4 9949.9 10026.8 10039.5 10040.9 9227.9 9263.2 9745.0 10236.7 10296.6 6993.4 7009.1 7145.7 7206.3 7207.0 7823.8 7937.8 7977.9 8239.3 8248.5 7323.1 7446.7 7464.6 7593.5 7612.4 7203.1 7237.5 7245.9 7320.2 7374.9 6941.2 6943.3 7017.7 7020.9 7044.6 4228.0 4784.5 4868.1 4972.7 5093.1 4813.2 5066.7 5075.1 5129.3 5203.5 19:20:37 INFO - PROCESS | 1973 | 19:20:37 INFO - PROCESS | 1973 | [#4] prototype.html Cycles:180 Average:4431.06 Median:5010.87 stddev:1370.37 (27.3%) stddev-sans-first:1338.76 19:20:37 INFO - PROCESS | 1973 | Values: 305.1 310.1 314.2 316.0 321.7 5269.4 5301.8 5310.3 5311.2 5322.9 5212.3 5217.3 5228.3 5243.8 5246.8 3191.2 3779.2 3812.7 3917.4 4001.3 3922.4 4009.6 4013.6 4028.0 4039.4 3036.6 3269.2 3523.4 3619.5 4018.5 2765.9 3283.7 3791.8 3899.3 3934.5 3460.7 3480.9 3493.7 3516.6 3531.3 4577.9 4989.0 5018.8 5040.4 5047.2 4279.9 4551.2 4596.2 4618.5 4667.7 4917.5 5079.5 5479.9 5491.2 5494.2 3881.5 3908.9 3915.0 3916.5 3919.9 5463.1 5775.5 5819.9 5823.4 5824.6 3324.2 3900.5 3946.4 3956.3 3965.7 3476.9 3972.2 3999.1 4023.9 4035.1 4301.4 4430.5 4606.3 4608.4 4638.0 4937.8 5505.8 5564.9 5607.7 5615.5 1224.5 1259.9 1337.3 1347.7 1393.2 744.5 791.7 793.3 793.9 804.4 4775.8 5002.9 5092.4 5122.3 5124.7 5450.7 5675.1 5700.9 5726.9 5803.5 4307.7 4348.8 4776.8 5122.5 5131.9 2335.7 2714.3 2941.0 2971.8 2984.9 4897.7 5048.5 5203.6 5409.8 5868.7 5285.6 5331.8 5750.6 5760.8 5771.3 5506.7 5719.1 5835.7 5879.8 5891.3 4632.1 5236.3 5615.8 5627.3 5658.8 4599.4 4609.8 5259.3 5600.5 5626.0 5382.5 5859.9 5903.9 5906.4 5916.5 3547.5 3816.5 3892.9 3901.8 3923.3 4690.7 4695.0 5621.2 5644.5 5645.5 4656.1 4775.0 5401.0 5410.8 5469.8 4427.5 5061.9 5444.3 5461.5 5520.4 4956.0 5214.4 5255.0 5371.1 5384.5 5026.5 5309.3 5397.7 5423.1 5435.4 4555.9 4938.3 5338.9 5344.0 5350.6 19:20:37 INFO - PROCESS | 1973 | 19:20:37 INFO - PROCESS | 1973 | [#5] yui.html Cycles:180 Average:17703.57 Median:57641.99 stddev:23931.07 (41.5%) stddev-sans-first:23964.51 19:20:37 INFO - PROCESS | 1973 | Values: 793.6 828.3 829.9 835.4 852.3 14789.4 15208.0 15212.9 15243.6 16251.2 15769.5 16201.5 16202.9 16223.8 16237.1 898.1 900.5 913.5 914.6 914.9 701.4 725.7 727.5 729.6 907.2 761.1 911.8 943.6 945.6 951.3 935.3 935.3 935.6 937.4 938.6 551.7 696.5 698.1 699.0 699.8 12348.2 12534.6 12545.1 14498.9 14960.6 13480.9 13500.4 13584.9 13927.0 13996.7 11017.8 11699.9 11704.5 11705.4 11740.7 3730.3 3737.9 3750.1 3878.2 3887.0 51976.5 75023.6 77984.6 80250.0 81884.6 10568.2 10642.7 10707.5 10715.8 10808.0 20623.1 20918.5 20941.6 20959.4 20999.8 7664.3 7706.3 7753.3 7845.4 7857.5 4373.2 4469.0 4482.6 4539.2 4539.8 3359.3 3470.9 3471.1 3471.1 3481.5 643.3 652.1 655.2 663.8 678.7 534.3 615.6 658.5 685.9 688.7 9218.5 9234.8 9254.4 9354.7 9393.1 590.8 667.8 688.0 690.4 693.5 3607.5 4358.8 4376.3 4417.9 4535.2 81965.0 84139.0 87822.0 87886.0 87956.6 76916.6 77221.0 77965.0 78000.0 78620.2 67165.0 67454.7 69239.3 70198.0 70620.9 8653.9 8711.9 8791.7 8798.9 8801.0 45010.5 45151.8 45170.3 45456.0 45523.8 56668.0 57421.4 57545.6 57738.4 58315.7 10118.6 11386.8 11574.2 11669.7 11763.1 10173.9 12811.2 12980.2 13068.7 13090.0 11581.9 11871.6 11940.5 11955.2 12346.9 11712.6 11840.2 11923.4 12028.4 12255.5 9617.2 9617.7 11806.2 12067.8 12150.6 9473.6 9735.2 9735.6 9788.2 10197.3 9236.5 9287.0 9494.2 9694.3 10092.3 19:20:37 INFO - PROCESS | 1973 | -------- Summary: end -------- 19:20:37 INFO - PROCESS | 1973 | 19:20:38 INFO - TEST-INFO | 1973: exit 0 19:20:38 INFO - TEST-OK | dromaeo_css | took 1115505ms 19:20:38 INFO - TEST-START | kraken 19:20:38 INFO - operating with platform_type : mac_ 19:20:38 INFO - Initialising browser for kraken test... 19:20:38 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpK9VHJs/profile http://localhost:49523/getInfo.html) 19:20:40 INFO - PROCESS | 1977 | __metrics Screen width/height:1600/1200 19:20:40 INFO - PROCESS | 1977 | colorDepth:24 19:20:40 INFO - PROCESS | 1977 | Browser inner width/height: 1024/689 19:20:40 INFO - PROCESS | 1977 | __metrics 19:20:40 INFO - PROCESS | 1977 | 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" 19:20:40 ERROR - PROCESS | 1977 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:20:41 INFO - TEST-INFO | 1977: exit 0 19:20:41 INFO - Browser initialized. 19:20:41 INFO - Running cycle 1/1 for kraken test... 19:20:41 INFO - TEST-INFO | started process 1978 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpK9VHJs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:20:53 INFO - PROCESS | 1978 | RSS: Main: 241577984 19:20:53 INFO - PROCESS | 1978 | 19:20:56 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49523/tests/kraken/driver.html?testName=audio-beat-detection) 19:20:56 INFO - PROCESS | 1978 | RSS: Main: 291078144 19:20:56 INFO - PROCESS | 1978 | 19:20:59 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49523/tests/kraken/driver.html?testName=audio-dft) 19:21:00 INFO - PROCESS | 1978 | RSS: Main: 301473792 19:21:00 INFO - PROCESS | 1978 | 19:21:03 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49523/tests/kraken/driver.html?testName=audio-fft) 19:21:04 INFO - PROCESS | 1978 | RSS: Main: 386760704 19:21:04 INFO - PROCESS | 1978 | 19:21:06 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49523/tests/kraken/driver.html?testName=audio-oscillator) 19:21:07 INFO - PROCESS | 1978 | RSS: Main: 288108544 19:21:07 INFO - PROCESS | 1978 | 19:21:09 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49523/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:21:09 INFO - PROCESS | 1978 | RSS: Main: 292134912 19:21:09 INFO - PROCESS | 1978 | 19:21:12 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49523/tests/kraken/driver.html?testName=imaging-darkroom) 19:21:13 INFO - PROCESS | 1978 | RSS: Main: 327831552 19:21:13 INFO - PROCESS | 1978 | 19:21:16 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49523/tests/kraken/driver.html?testName=imaging-desaturate) 19:21:16 INFO - PROCESS | 1978 | RSS: Main: 331096064 19:21:16 INFO - PROCESS | 1978 | 19:21:19 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49523/tests/kraken/driver.html?testName=json-parse-financial) 19:21:20 INFO - PROCESS | 1978 | RSS: Main: 330276864 19:21:20 INFO - PROCESS | 1978 | 19:21:21 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49523/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:21:22 INFO - PROCESS | 1978 | RSS: Main: 309563392 19:21:22 INFO - PROCESS | 1978 | 19:21:24 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:21:24 INFO - PROCESS | 1978 | RSS: Main: 317124608 19:21:24 INFO - PROCESS | 1978 | 19:21:27 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:21:27 INFO - PROCESS | 1978 | RSS: Main: 319614976 19:21:27 INFO - PROCESS | 1978 | 19:21:30 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:21:31 INFO - PROCESS | 1978 | RSS: Main: 322985984 19:21:31 INFO - PROCESS | 1978 | 19:21:34 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:21:34 INFO - PROCESS | 1978 | RSS: Main: 323395584 19:21:34 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | Cycle 1(1): loaded http://localhost:49523/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49523/tests/kraken/driver.html?testName=ai-astar) 19:21:36 INFO - PROCESS | 1978 | RSS: Main: 366948352 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | __start_tp_report 19:21:36 INFO - PROCESS | 1978 | _x_x_mozilla_page_load 19:21:36 INFO - PROCESS | 1978 | _x_x_mozilla_page_load_details 19:21:36 INFO - PROCESS | 1978 | |i|pagename|runs| 19:21:36 INFO - PROCESS | 1978 | |0;ai-astar;113;107;112;113;110;112;111;112;113;111 19:21:36 INFO - PROCESS | 1978 | |1;audio-beat-detection;156;127;116;174;119;119;140;115;150;118 19:21:36 INFO - PROCESS | 1978 | |2;audio-dft;186;184;186;187;184;173;223;183;183;182 19:21:36 INFO - PROCESS | 1978 | |3;audio-fft;79;73;56;109;57;57;63;65;90;80 19:21:36 INFO - PROCESS | 1978 | |4;audio-oscillator;87;88;93;72;72;69;98;72;68;104 19:21:36 INFO - PROCESS | 1978 | |5;imaging-gaussian-blur;89;84;73;113;133;131;86;84;87;84 19:21:36 INFO - PROCESS | 1978 | |6;imaging-darkroom;92;93;93;93;92;93;92;93;93;92 19:21:36 INFO - PROCESS | 1978 | |7;imaging-desaturate;84;85;84;86;85;106;139;138;85;87 19:21:36 INFO - PROCESS | 1978 | |8;json-parse-financial;56;56;53;57;54;55;56;55;54;54 19:21:36 INFO - PROCESS | 1978 | |9;json-stringify-tinderbox;64;64;77;82;75;79;71;71;70;73 19:21:36 INFO - PROCESS | 1978 | |10;stanford-crypto-aes;85;85;85;86;88;84;85;86;124;96 19:21:36 INFO - PROCESS | 1978 | |11;stanford-crypto-ccm;160;152;146;120;152;143;141;133;152;140 19:21:36 INFO - PROCESS | 1978 | |12;stanford-crypto-pbkdf2;170;219;176;165;165;162;166;165;164;161 19:21:36 INFO - PROCESS | 1978 | |13;stanford-crypto-sha256-iterative;64;66;67;64;81;85;84;84;66;66 19:21:36 INFO - PROCESS | 1978 | __end_tp_report 19:21:36 INFO - PROCESS | 1978 | __start_cc_report 19:21:36 INFO - PROCESS | 1978 | _x_x_mozilla_cycle_collect,1905 19:21:36 INFO - PROCESS | 1978 | __end_cc_report 19:21:36 INFO - PROCESS | 1978 | __startTimestamp1465352496953__endTimestamp 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | ------- Summary: start ------- 19:21:36 INFO - PROCESS | 1978 | Number of tests: 14 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:1.84 (1.6%) stddev-sans-first:1.86 19:21:36 INFO - PROCESS | 1978 | Values: 113.0 107.0 112.0 113.0 110.0 112.0 111.0 112.0 113.0 111.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:133.50 stddev:20.58 (15.4%) stddev-sans-first:20.14 19:21:36 INFO - PROCESS | 1978 | Values: 156.0 127.0 116.0 174.0 119.0 119.0 140.0 115.0 150.0 118.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#2] audio-dft Cycles:10 Average:187.10 Median:185.00 stddev:13.20 (7.1%) stddev-sans-first:14.00 19:21:36 INFO - PROCESS | 1978 | Values: 186.0 184.0 186.0 187.0 184.0 173.0 223.0 183.0 183.0 182.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#3] audio-fft Cycles:10 Average:72.90 Median:69.00 stddev:17.11 (24.8%) stddev-sans-first:18.01 19:21:36 INFO - PROCESS | 1978 | Values: 79.0 73.0 56.0 109.0 57.0 57.0 63.0 65.0 90.0 80.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#4] audio-oscillator Cycles:10 Average:82.30 Median:79.50 stddev:13.28 (16.7%) stddev-sans-first:13.97 19:21:36 INFO - PROCESS | 1978 | Values: 87.0 88.0 93.0 72.0 72.0 69.0 98.0 72.0 68.0 104.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:84.00 stddev:21.27 (25.3%) stddev-sans-first:22.39 19:21:36 INFO - PROCESS | 1978 | Values: 89.0 84.0 73.0 113.0 133.0 131.0 86.0 84.0 87.0 84.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.52 (0.6%) stddev-sans-first:0.50 19:21:36 INFO - PROCESS | 1978 | Values: 92.0 93.0 93.0 93.0 92.0 93.0 92.0 93.0 93.0 92.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#7] imaging-desaturate Cycles:10 Average:97.90 Median:85.00 stddev:22.38 (26.3%) stddev-sans-first:23.17 19:21:36 INFO - PROCESS | 1978 | Values: 84.0 85.0 84.0 86.0 85.0 106.0 139.0 138.0 85.0 87.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.50 stddev:1.25 (2.2%) stddev-sans-first:1.27 19:21:36 INFO - PROCESS | 1978 | Values: 56.0 56.0 53.0 57.0 54.0 55.0 56.0 55.0 54.0 54.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:74.00 stddev:5.91 (8.0%) stddev-sans-first:5.39 19:21:36 INFO - PROCESS | 1978 | Values: 64.0 64.0 77.0 82.0 75.0 79.0 71.0 71.0 70.0 73.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:85.50 stddev:12.30 (14.4%) stddev-sans-first:12.89 19:21:36 INFO - PROCESS | 1978 | Values: 85.0 85.0 85.0 86.0 88.0 84.0 85.0 86.0 124.0 96.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#11] stanford-crypto-ccm Cycles:10 Average:143.90 Median:149.00 stddev:11.43 (7.7%) stddev-sans-first:10.53 19:21:36 INFO - PROCESS | 1978 | Values: 160.0 152.0 146.0 120.0 152.0 143.0 141.0 133.0 152.0 140.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:165.50 stddev:17.30 (10.5%) stddev-sans-first:18.34 19:21:36 INFO - PROCESS | 1978 | Values: 170.0 219.0 176.0 165.0 165.0 162.0 166.0 165.0 164.0 161.0 19:21:36 INFO - PROCESS | 1978 | 19:21:36 INFO - PROCESS | 1978 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:74.00 stddev:9.39 (12.7%) stddev-sans-first:9.42 19:21:36 INFO - PROCESS | 1978 | Values: 64.0 66.0 67.0 64.0 81.0 85.0 84.0 84.0 66.0 66.0 19:21:36 INFO - PROCESS | 1978 | -------- Summary: end -------- 19:21:36 INFO - PROCESS | 1978 | 19:21:37 INFO - TEST-INFO | 1978: exit 0 19:21:37 INFO - TEST-OK | kraken | took 59420ms 19:21:37 INFO - SUITE-END | took 1174s 19:21:38 INFO - Completed test suite (00:19:35) 19:21:38 INFO - javascript benchmark 19:21:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3222.6777322267776, 3393.6267010628826, 3409.1306716787217, 3414.334204830056, 3451.0854623524756, 5485.506304432601, 5519.862003449915, 5574.247476590659, 5609.635373700707, 5629.7466614002315, 5392.811251606195, 5542.944570554293, 5650.51970582501, 5660.0, 5698.487136157745, 4862.683925544846, 4878.560929516343, 4936.703797772145, 4974.900501989958, 5113.002964421939, 5012.874678133039, 5055.51972562606, 5056.747162641853, 5097.974510127444, 5118.360204974379, 4192.979035104805, 5040.143175660147, 5051.06555287273, 5133.3326667533165, 5196.532312091911, 5102.515261050195, 5116.309298244727, 5125.154349532323, 5129.871753206163, 5140.794368225266, 5054.974725126351, 5068.087744206078, 5087.56755675768, 5107.463716309809, 5121.052605268037, 5468.699221542814, 5491.0, 5507.7246137693355, 5578.944210557883, 5601.663900166003, 6097.021124203537, 6109.265503518817, 6133.846653833645, 6152.138700581922, 6165.691715414257, 5689.772409103631, 6200.472959798422, 6237.812865613993, 6262.780802671885, 6266.624002559861, 5169.431362550116, 5684.033714268584, 5686.886262274731, 5701.800436984686, 5715.114157305624, 6285.96857015712, 6290.842728931813, 6294.748210071546, 6339.334369891188, 6342.985122380397, 5864.354920958691, 6061.269364322841, 6064.151018857364, 6074.726637301332, 6081.939180608226, 5810.244668193108, 6054.753608760578, 6118.923762351576, 6149.169862068653, 6153.84615384619, 6107.206063211754, 6113.052476866093, 6122.479589234876, 6126.509879209652, 6128.325884152829, 5972.701364931736, 6060.606060606135, 6138.7237574309265, 6152.630842149485, 6182.907256391158, 4224.725392849455, 4744.122337367598, 4789.880252993703, 4816.927746083811, 4819.662623616383, 4308.482982042175, 4314.115606300701, 4341.34879768031, 4341.804618792162, 4390.319500477431, 5648.15277708347, 5667.0, 5675.886482270332, 5696.857578560486, 5731.140328950691, 6096.146539484475, 6127.448529632355, 6229.750809967551, 6254.0, 6256.0, 4665.673402861835, 4725.9527404726205, 5658.886822263532, 5674.347450043216, 5684.9431505684415, 5299.2845965794895, 5326.893462130735, 5331.893362132736, 5334.466553344634, 5336.8398948030845, 6216.409441103088, 6234.064890266496, 6240.500759939194, 6271.843203919939, 6281.937180628227, 6134.436587033385, 6203.193584833943, 6228.56400051992, 6254.593451425642, 6256.874862502816, 6263.154474146114, 6275.152854364785, 6284.277308109626, 6300.369963003663, 6305.432511074026, 5989.550783691154, 6062.727177277121, 6078.665673388006, 6100.450959413674, 6108.602940808833, 5289.550388217045, 6083.8744832206185, 6211.099390588249, 6222.191115155182, 6235.220597425322, 6067.150598916067, 6073.331933487401, 6096.969515152396, 6111.663858487826, 6124.38756124384, 5759.1649210865025, 5947.553933455095, 5988.071848863433, 6031.276246850406, 6086.6348019119, 5850.0, 5877.059670452878, 5878.353381128158, 5892.322382926019, 5931.436513531207, 6066.400744979152, 6084.777573676266, 6091.839318428981, 6111.8820597758495, 6123.2958209804665, 5739.971300143639, 5743.62666426681, 5784.074548072456, 5840.795572154954, 5888.911666325094], "value": 5588.552378709947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.0116856354501, 490.98281560145404, 501.3532543019505, 507.49250749250774, 515.99381206647, 30264.092077237663, 40842.38736418956, 41612.583874161246, 41866.138740576345, 42499.36250956238, 41272.0, 41828.79085604568, 43872.56127438725, 43908.560914390844, 43981.34027989575, 8624.439411438269, 9454.810903781903, 9673.419594824298, 9678.37090589113, 9682.322237443383, 11421.828672569918, 11657.125715571323, 11659.475323610415, 11890.048796096335, 11893.762124757499, 11588.536458541648, 11652.359120248382, 11665.766684666301, 11669.474873630665, 11683.941580292087, 11669.241499302518, 11791.941040294829, 11793.469293881795, 11797.823032654516, 11806.46870890812, 11453.198276120675, 11534.134939879543, 11535.653930382103, 11537.653870383901, 11553.19127661064, 32698.0, 40439.393409098884, 40442.19115617671, 40466.190676186605, 40522.39216411756, 3496.527968724215, 3506.4740288956605, 3508.1405055761247, 3550.5029295898594, 3650.9634903651154, 31171.532427013386, 31364.112802595457, 31895.681043189503, 32274.031779046665, 32523.0, 3270.8201048942296, 3299.071700554984, 3303.7687361884678, 3326.2515933914674, 3386.0857568456627, 491784.0, 492407.0, 492461.53769231285, 492981.0, 495660.0, 59427.0, 59824.0, 60235.096473552934, 60333.79332413327, 60351.698241508515, 62118.68940655268, 62171.37828621746, 62291.0, 62330.688346557974, 62424.687876560325, 15971.520854374387, 16492.25784839685, 16519.504414867573, 16532.586685332964, 16550.33798648065, 12714.0, 12806.67983300425, 12806.80789788154, 12809.487620495262, 12824.615261542169, 15951.680966380607, 16153.757693634605, 16222.837771622133, 16224.756628650579, 16231.18844057816, 5564.944350556523, 5796.594238403356, 6223.222097237845, 6272.247330320391, 6275.780347687809, 939.8026414453042, 1090.9781804364027, 1113.980707652505, 1127.3968426891574, 1175.5121624525898, 26747.33131671724, 30138.0, 30735.538966915516, 30974.0, 31190.844045779624, 1075.3440401355167, 1106.4633652678444, 1150.7238262816863, 1181.7754626620915, 1202.9939850300693, 15666.373345066298, 15688.921555392379, 15691.293891774669, 15900.284487198103, 16126.67746645084, 662846.0, 666089.0, 670509.6474517597, 670569.0, 675436.0, 59369.10946335809, 59403.0, 59431.0, 59433.10850337248, 59619.50951226254, 1267.283984548727, 1304.243538747524, 1321.3855557165964, 1416.214001229307, 1426.493594773861, 26974.32564185911, 28971.85514072416, 31445.0, 31532.369352613277, 31817.5227371585, 49430.75284623554, 50798.746006269736, 50815.0, 50819.0, 50833.74583127061, 552789.0, 570290.0, 570879.0, 572186.0, 573829.0, 34629.13427164341, 36018.45972310365, 40774.0, 41115.50518205867, 41185.38221926614, 45977.0, 47972.7601361991, 48023.5197648019, 49134.508654914425, 49138.50861491483, 38413.615863841005, 39712.80143599264, 40015.59984400119, 40541.59458405378, 40769.59230407658, 38590.614093858705, 38781.0, 38857.417138743505, 39587.01032474096, 39692.0, 32574.837125814218, 38131.80934095312, 38484.80757596306, 38713.0, 38805.611943881326, 33923.49114763231, 34472.82763586166, 35475.64524354723, 35476.46785298274, 35717.82141089278, 25175.622365664894, 25593.0, 25721.228363149134, 25728.485430291665, 26365.60451593266], "value": 20648.156164264732, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.90882188274816, 383.5033631447277, 386.1389102301866, 398.6272834899371, 404.88663174311205, 20234.494137646565, 21981.780182198174, 22340.55318893621, 22434.43913902153, 22439.0, 20769.96150192488, 20927.895360523176, 21466.248681296158, 21532.56934861302, 21538.0, 5537.36320323163, 5777.768889244425, 5875.059990401536, 5878.794242201524, 5963.01610234313, 4124.381342798574, 4352.12957408518, 5795.913061304083, 6178.227721534809, 6509.218893732735, 6412.775552855651, 6486.772962946297, 6499.675016249193, 6500.9674951625175, 6514.967425162868, 6279.120923070774, 6427.0, 6492.383223593751, 6501.349865013508, 6516.413522782948, 3683.208110256281, 3843.730938834283, 3900.31744444721, 5590.580706447033, 5596.0, 26249.0, 26581.335466613295, 26606.6009009865, 26905.461890762075, 26910.865445672647, 2468.987894671159, 2811.973629625172, 3127.218195451137, 3137.7176054155007, 3234.498652708834, 7093.503454758169, 7099.680514376813, 11568.248063875906, 13475.315585551805, 15959.202039898075, 1802.1079565614973, 1829.3414370826492, 1848.3992702371784, 1903.2101677803832, 1919.89440580768, 112305.0, 115877.0, 121312.39343803313, 123909.38045309715, 124098.2770516895, 10834.187435942336, 11165.385903775208, 11226.775464490664, 11238.943805280922, 11415.543378264776, 14168.433262669378, 14240.0, 14254.643633909236, 14255.572332830032, 14257.64355891111, 8774.517401542977, 8808.603612837438, 8866.601002954883, 8884.333674974403, 9001.459912405277, 8835.0, 8854.335924805537, 8861.556922153866, 8977.640894364153, 8995.23540499065, 5659.3208814941645, 6654.600723956578, 6798.422134118655, 6810.148731408574, 6859.862802743917, 1653.2477722636172, 1710.1107424139377, 1762.8237176282269, 1807.3945228348387, 1818.2454281473306, 877.3244601656688, 948.9240860731124, 1041.9010194031555, 1046.6650671784955, 1106.1537923488538, 21252.468688282916, 21985.890070549543, 21995.780042199374, 22007.009684564233, 22086.779132208794, 817.6157206113118, 971.6793458158791, 1017.211660962745, 1020.1940467031111, 1109.8890110988837, 9334.766630834147, 11851.288922664495, 12162.0, 12193.085518586142, 12226.633201003984, 562493.0, 571069.0, 582306.0, 584247.0, 586246.0, 102437.0, 103453.0, 106872.17533895982, 112707.43646281716, 115412.0, 873.724776695339, 1014.9797004059877, 1016.9796604067877, 1033.6847261585137, 1038.958441662325, 19206.0, 20284.391468255977, 20419.8979005104, 20604.484887877323, 20637.69043464319, 32167.0, 35326.47010294796, 36403.63596364002, 41862.0, 51064.48935510746, 66417.33582664111, 66634.33365666475, 66946.99579506213, 67050.63594912298, 68030.0, 7024.754133605299, 7479.289467500579, 8176.264136227768, 8208.917910820815, 8427.283680887193, 8780.473171609468, 8824.602892869709, 8945.865812012695, 11814.0, 13370.465181392636, 7972.481788683717, 8053.718119865776, 8093.109757926505, 8145.959270203611, 8242.381821363302, 7656.96630954814, 8089.433739638169, 8092.069412017693, 8271.296939760188, 8285.917140828515, 7782.727604533814, 7841.059072911287, 8257.545834978893, 8465.98408191021, 8557.400981931205, 12601.495940162291, 12751.298678572397, 12806.935965320114, 12974.351282435651, 13136.277504736949, 11961.700957476132, 12011.579594714143, 12029.0, 12070.75858482843, 12119.939400302943], "value": 9517.844396549874, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.9859301406982, 1563.86707129894, 1586.6667999720057, 1832.761740973673, 1982.1476764991057, 7069.540479868811, 8534.57327133644, 8749.0, 8754.343424243183, 8903.866442003358, 8488.236058754726, 8734.301255899529, 8742.344324175681, 8743.431676940994, 8748.300135989122, 2103.4110449074205, 2157.2557467673705, 2313.063209400187, 2328.2549584133085, 2358.6462030695448, 2190.080166330145, 2219.511707424372, 2377.3818807110188, 2418.7218469876016, 2418.7944024757917, 2328.976710232893, 2355.564220619191, 2366.2782851230368, 2368.834181607288, 2370.5495955768433, 2070.7722150563427, 2257.6387777955447, 2310.953780924372, 2311.0293676655847, 2311.687922130516, 1955.9804401955942, 2343.414146463384, 2363.3855197648563, 2367.5856725073045, 2367.7277113132036, 8679.956600217021, 8752.168543988311, 8752.387332886701, 8754.3871928965, 8761.7809554761, 5139.17773156297, 5313.256144139785, 5315.202719592054, 5315.893682126374, 5316.149416093406, 3400.8129552874584, 3470.0804286864, 3624.5106910567265, 3629.0927268182954, 3631.7094632429607, 1063.2291588598282, 1075.8870318616512, 1104.7293413113757, 1120.0678829019957, 1125.9774804503863, 11697.3566453845, 11710.121740869468, 11715.297082175011, 11721.531138754439, 11736.471858766376, 3831.8084095795098, 4188.560201178893, 4661.091087238024, 4787.042591481718, 4798.904021919542, 9770.902290977141, 9789.902100979041, 9814.0, 9817.312788104908, 9828.017198280115, 5314.229436731653, 5322.893542129135, 5349.839504814861, 5356.4107948125675, 5358.169483730028, 4392.033752574429, 4494.258447356215, 4743.786529606176, 4834.854954351375, 4841.15279826029, 3903.8828835134423, 3949.7827619481204, 4020.6984476164403, 4042.8989275268355, 4069.064115253508, 1288.4910460368103, 1297.228149251213, 1302.635262126606, 1308.2346827106248, 1309.4500309869877, 913.1234015345343, 920.7375897869075, 931.1340453378423, 938.441627231796, 976.0971101731012, 5902.1146827976145, 5908.527317814564, 5996.880062398727, 6080.0, 6153.384661533811, 427.20753003179277, 445.944256967879, 902.4449963272672, 961.7257134297084, 1124.791913496006, 3752.3433399155037, 3961.8811435656976, 3999.1601763629383, 4033.576474470197, 4133.586641335903, 10293.588256469808, 10451.477426128664, 10651.786964260671, 10661.466926653637, 10789.730256743644, 10496.68509944703, 10581.99471050249, 10705.411202383788, 10735.94632026835, 10748.247622666338, 8013.959930200312, 8054.758357249292, 8136.308413784776, 8181.550014749127, 8202.220789025152, 5775.8556036099435, 6019.217501724747, 6058.182145410313, 6078.088286757022, 6095.664738439428, 9764.365316254421, 9949.850752238577, 10026.799464010825, 10039.498025098861, 10040.895501494684, 9227.907720922973, 9263.16631503168, 9744.951275243578, 10236.692899213036, 10296.639617613348, 6993.440524758109, 7009.12385951756, 7145.714171433292, 7206.279372062962, 7206.963965180141, 7823.84352312962, 7937.801554961172, 7977.880331794911, 8239.299659529008, 8248.505089694638, 7323.084614423197, 7446.664900079618, 7464.552126872405, 7593.544387336557, 7612.3910087194, 7203.099612548432, 7237.493375463667, 7245.927540724525, 7320.194778574246, 7374.926250737424, 6941.167059952838, 6943.305669433016, 7017.684204210721, 7020.894686579808, 7044.6125463100025, 4228.0, 4784.497627749036, 4868.075065737498, 4972.65191436596, 5093.0577843099145, 4813.205821039489, 5066.746662666926, 5075.137226671401, 5129.256257842667, 5203.453637368022], "value": 4540.022455917457, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.1075603858713, 310.1440025529538, 314.2050611951762, 315.9935605097765, 321.74903575211346, 5269.446708095652, 5301.8409447716585, 5310.336207974004, 5311.1502159654465, 5322.893542129155, 5212.322398088253, 5217.260938007548, 5228.29418028566, 5243.842684719464, 5246.842594722164, 3191.1862475068824, 3779.225258821935, 3812.7140464465137, 3917.373220284755, 4001.339778936472, 3922.3724204127348, 4009.6391324780766, 4013.618706222904, 4028.0262671291002, 4039.4142849286836, 3036.5596988436664, 3269.2019389335887, 3523.4362501999685, 3619.457081437792, 4018.4976877890263, 2765.9170224893082, 3283.7029373397613, 3791.7535360201637, 3899.259140763246, 3934.4688467057013, 3460.688538031564, 3480.94778578319, 3493.7205023598053, 3516.595591506984, 3531.346700860349, 4577.924187815861, 4988.950110498885, 5018.799248030111, 5040.395152581677, 5047.192449208146, 4279.850205242833, 4551.249043907785, 4596.218642830727, 4618.49196588375, 4667.6732628716, 4917.532834380728, 5079.517445842639, 5479.904019196177, 5491.176323551459, 5494.230807686927, 3881.514810648669, 3908.9413658795143, 3914.9804250978564, 3916.5104361954754, 3919.941200881989, 5463.0606134822365, 5775.509081728016, 5819.912701309441, 5823.388544202882, 5824.621399609055, 3324.1523411529906, 3900.531936167678, 3946.408038794204, 3956.3274243378696, 3965.6629186519467, 3476.8873960332958, 3972.205558888234, 3999.140399714147, 4023.939640905389, 4035.1324465240114, 4301.354666879846, 4430.490493593214, 4606.286025666027, 4608.400907881954, 4638.002829391629, 4937.802487900443, 5505.834824955257, 5564.86087847799, 5607.663540187602, 5615.466530679579, 1224.5469176404788, 1259.9118061735774, 1337.2511393619604, 1347.6698208938872, 1393.1501783911806, 744.5123444144094, 791.7110254756972, 793.3018943329948, 793.9364850811921, 804.4409135650784, 4775.785089670973, 5002.949970500321, 5092.439831618519, 5122.334096567497, 5124.718140502234, 5450.727463626803, 5675.0636145035605, 5700.914486282709, 5726.909677967596, 5803.47768700819, 4307.698461107692, 4348.847790327323, 4776.832810851603, 5122.487751224848, 5131.871703207375, 2335.66132910727, 2714.3214196450886, 2940.9705902940696, 2971.7622590193164, 2984.925376865552, 4897.706137631753, 5048.495150484922, 5203.60972927032, 5409.810656626999, 5868.713520428573, 5285.603579731535, 5331.760070796823, 5750.568707346965, 5760.769569217268, 5771.278590176228, 5506.68719137963, 5719.113537401709, 5835.708214589338, 5879.794207202727, 5891.3224979127945, 4632.143053535106, 5236.298203083937, 5615.848751005952, 5627.296587926509, 5658.801941932013, 4599.356090147315, 4609.838655647036, 5259.289995850511, 5600.4959553640365, 5626.0, 5382.461753824587, 5859.9121013183985, 5903.940960590339, 5906.40935906406, 5916.455923952742, 3547.467879818048, 3816.4847745555107, 3892.8637497687446, 3901.848954558469, 3923.2737595457907, 4690.695104818176, 4694.953050469451, 5621.213030175697, 5644.520215781705, 5645.548356131581, 4656.068786242698, 4774.976125119352, 5401.027814993339, 5410.756515956713, 5469.7573994662, 4427.46870375557, 5061.924071139009, 5444.26502422168, 5461.4980880258245, 5520.371490410258, 4955.975220123876, 5214.42641309448, 5255.0, 5371.060064488777, 5384.542313903362, 5026.52248036436, 5309.309789727311, 5397.730113494388, 5423.105187643994, 5435.374931882884, 4555.908881822279, 4938.30863679078, 5338.919916201337, 5344.0, 5350.5922334948655], "value": 3992.2802221893926, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.5714714054411, 828.3000864269694, 829.9253067223952, 835.3651225068945, 852.3011130872685, 14789.408423663053, 15208.011479253868, 15212.923935380322, 15243.618909527268, 16251.187440627951, 15769.526914192593, 16201.513954581353, 16202.918985405086, 16223.83776162241, 16237.106959117244, 898.1063841477738, 900.5002223765807, 913.5386629751999, 914.5518695839057, 914.9130832570895, 701.3722718167237, 725.7205975699368, 727.5380133615166, 729.6205972894119, 907.2152588011355, 761.0942977856333, 911.8221946720392, 943.6131186213654, 945.6359301668873, 951.3103000324778, 935.2751617496494, 935.2985261054209, 935.5789894547447, 937.4422218779815, 938.5870217104521, 551.6689986008363, 696.4532841719226, 698.1064237775655, 698.9615571143636, 699.7935608995308, 12348.197367171195, 12534.623961281084, 12545.1218414711, 14498.855011449856, 14960.625984350478, 13480.93259533696, 13500.392482338319, 13584.932075339659, 13927.0, 13996.72006559863, 11017.779644407146, 11699.947004769612, 11704.531818727326, 11705.414729263588, 11740.70648233801, 3730.2725968436166, 3737.9252414951548, 3750.062484378905, 3878.1855810279876, 3886.9805650971566, 51976.480235197545, 75023.62488187633, 77984.61007694925, 80250.0, 81884.59057704733, 10568.15454763617, 10642.680719578426, 10707.464626768631, 10715.785684286271, 10808.0, 20623.071961761758, 20918.477038073866, 20941.581168376546, 20959.37121886346, 20999.79000210009, 7664.31021208094, 7706.306432421109, 7753.302202801775, 7845.411594130463, 7857.489263197874, 4373.222695894293, 4469.0, 4482.596566309048, 4539.182947069493, 4539.750313732777, 3359.3113411750533, 3470.8611655533496, 3471.09751464621, 3471.1495683557187, 3481.47777833327, 643.3052303512195, 652.083822229768, 655.1712084213474, 663.8307231655897, 678.7285085965653, 534.3347532322283, 615.5967841064111, 658.4534836086037, 685.9451243900476, 688.6659969914588, 9218.539073046455, 9234.815303694022, 9254.444733316022, 9354.71935841926, 9393.067188115827, 590.8109404990362, 667.8279619268153, 687.9504437429193, 690.433844247712, 693.5145398221265, 3607.494950706903, 4358.782060896942, 4376.299792033323, 4417.929380518664, 4535.160995215896, 81965.0, 84139.0, 87822.0, 87886.0, 87956.56021719851, 76916.61541692256, 77221.0, 77965.0, 78000.0, 78620.21379786129, 67165.0, 67454.65090698158, 69239.26901827393, 70198.0, 70620.94068588976, 8653.913460865562, 8711.869321960048, 8791.692290770048, 8798.912010880065, 8800.955995219983, 45010.54989450194, 45151.77424112859, 45170.322445164005, 45456.0, 45523.77238113788, 56668.0, 57421.42578574161, 57545.56136096631, 57738.42261577499, 58315.708421457624, 10118.645847395306, 11386.829197562209, 11574.247673901169, 11669.708257293636, 11763.059375109093, 10173.949130254301, 12811.231326120462, 12980.22118672845, 13068.673283167996, 13090.0, 11581.88418115808, 11871.584494542649, 11940.52237910474, 11955.163138580216, 12346.938265308616, 11712.648620541397, 11840.171188016757, 11923.403829808301, 12028.398580071136, 12255.509779608716, 9617.23062155012, 9617.663381781604, 11806.232594881305, 12067.75864482723, 12150.57472988441, 9473.573689183866, 9735.22118230554, 9735.561899714388, 9788.216942644714, 10197.337173083726, 9236.491992940453, 9287.0, 9494.240460762985, 9694.321397502106, 10092.34399764013], "value": 7028.459439010352, "unit": "score"}], "name": "dromaeo_css", "value": 7205.285909313221, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 112.0, 113.0, 110.0, 112.0, 111.0, 112.0, 113.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 127.0, 116.0, 174.0, 119.0, 119.0, 140.0, 115.0, 150.0, 118.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 186.0, 187.0, 184.0, 173.0, 223.0, 183.0, 183.0, 182.0], "value": 187.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 56.0, 109.0, 57.0, 57.0, 63.0, 65.0, 90.0, 80.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 93.0, 72.0, 72.0, 69.0, 98.0, 72.0, 68.0, 104.0], "value": 82.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 73.0, 113.0, 133.0, 131.0, 86.0, 84.0, 87.0, 84.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 92.0, 93.0, 93.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 86.0, 85.0, 106.0, 139.0, 138.0, 85.0, 87.0], "value": 97.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 57.0, 54.0, 55.0, 56.0, 55.0, 54.0, 54.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 77.0, 82.0, 75.0, 79.0, 71.0, 71.0, 70.0, 73.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 86.0, 88.0, 84.0, 85.0, 86.0, 124.0, 96.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 152.0, 146.0, 120.0, 152.0, 143.0, 141.0, 133.0, 152.0, 140.0], "value": 143.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 219.0, 176.0, 165.0, 165.0, 162.0, 166.0, 165.0, 164.0, 161.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 67.0, 64.0, 81.0, 85.0, 84.0, 84.0, 66.0, 66.0], "value": 72.7, "unit": "score"}], "name": "kraken", "value": 1479.9, "alertThreshold": 2.0}]} 19:21:38 INFO - Return code: 0 19:21:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:21:38 ERROR - # TBPL SUCCESS # 19:21:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:21:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:21:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:21:38 INFO - 2016-06-07 19:21:38.253 screenresolution[1982:15659] starting screenresolution argv=screenresolution get 19:21:38 INFO - 2016-06-07 19:21:38.256 screenresolution[1982:15659] Display 0: 1600x1200x32@60 19:21:38 INFO - 2016-06-07 19:21:38.261 screenresolution[1983:15663] starting screenresolution argv=screenresolution list 19:21:38 INFO - Available Modes on Display 0 19:21:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:21:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:21:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:21:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:21:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:21:38 INFO - Intel Iris: 19:21:38 INFO - Chipset Model: Intel Iris 19:21:38 INFO - Type: GPU 19:21:38 INFO - Bus: Built-In 19:21:38 INFO - VRAM (Dynamic, Max): 1536 MB 19:21:38 INFO - Vendor: Intel (0x8086) 19:21:38 INFO - Device ID: 0x0a2e 19:21:38 INFO - Revision ID: 0x0009 19:21:38 INFO - Displays: 19:21:38 INFO - Display: 19:21:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:21:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:21:38 INFO - Display Serial Number: 1600x1200 60 19:21:38 INFO - Main Display: Yes 19:21:38 INFO - Mirror: Off 19:21:38 INFO - Online: Yes 19:21:38 INFO - Rotation: Supported 19:21:38 INFO - Return code: 0 19:21:38 INFO - Running post-action listener: _resource_record_post_action 19:21:38 INFO - ##### 19:21:38 INFO - ##### Finished run-tests step (success) 19:21:38 INFO - ##### 19:21:38 INFO - Running post-run listener: _resource_record_post_run 19:21:38 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 1101824; Write bytes: 694880768; Read time: 14; Write time: 2346 19:21:38 INFO - TinderboxPrint: CPU usage
53.8% 19:21:38 INFO - TinderboxPrint: I/O read bytes / time
1,101,824 / 14 19:21:38 INFO - TinderboxPrint: I/O write bytes / time
694,880,768 / 2,346 19:21:38 INFO - TinderboxPrint: CPU idle
2,238.7 (46.2%) 19:21:38 INFO - TinderboxPrint: CPU system
968.4 (20.0%) 19:21:38 INFO - TinderboxPrint: CPU user
1,634.5 (33.8%) 19:21:38 INFO - TinderboxPrint: Swap in / out
399,675,392 / 0 19:21:38 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 383179264; Write bytes: 377894400; Read time: 28227; Write time: 699 19:21:38 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1101824; Write bytes: 293987328; Read time: 14; Write time: 1603 19:21:38 INFO - Running post-run listener: _upload_blobber_files 19:21:38 INFO - Blob upload gear active. 19:21:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:21:38 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 19:21:38 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:21:38 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:21:39 INFO - (blobuploader) - INFO - Open directory for files ... 19:21:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:21:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:21:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:40 INFO - (blobuploader) - INFO - Done attempting. 19:21:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:21:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:21:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:41 INFO - (blobuploader) - INFO - Done attempting. 19:21:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:21:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:21:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:21:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:21:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:21:43 INFO - (blobuploader) - INFO - Done attempting. 19:21:43 INFO - (blobuploader) - INFO - Iteration through files over. 19:21:43 INFO - Return code: 0 19:21:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:21:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:21:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1865e238dfb7c7f4dbfcdeadf951f2f411c5b7a2bc2bb6593b6ab15b6a31454d7ffdb952de17b2a5551ff9b943e3ea897dd0ecf7a6e26e30ef4f0c5e281370dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56f087ccd9a2a43c564c5517a869abcd50688fb3743be0349d5dc73db71a7aea8b16473d826bd1024139186d4dcb62a87e8e796e7a6756e2438762e963e9823a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/155fd947e9b17824e78aadb3f6089cede590a58643500be06df712266dcb43fb989548ef09b98390d4067c2444a8972e3970ab62ae3a79555f266e5d564c9dcf"} 19:21:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:21:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:21:43 INFO - Contents: 19:21:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1865e238dfb7c7f4dbfcdeadf951f2f411c5b7a2bc2bb6593b6ab15b6a31454d7ffdb952de17b2a5551ff9b943e3ea897dd0ecf7a6e26e30ef4f0c5e281370dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56f087ccd9a2a43c564c5517a869abcd50688fb3743be0349d5dc73db71a7aea8b16473d826bd1024139186d4dcb62a87e8e796e7a6756e2438762e963e9823a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/155fd947e9b17824e78aadb3f6089cede590a58643500be06df712266dcb43fb989548ef09b98390d4067c2444a8972e3970ab62ae3a79555f266e5d564c9dcf"} 19:21:43 INFO - Running post-run listener: copy_logs_to_upload_dir 19:21:43 INFO - Copying logs to upload dir... 19:21:43 INFO - mkdir: /builds/slave/test/build/upload/logs 19:21:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.780029 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-06-07 19:21:43.910086) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 19:21:43.914652) ========= 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.fE9231BAdz/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.dPsc0mCVUS/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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/Autoland/sha512/1865e238dfb7c7f4dbfcdeadf951f2f411c5b7a2bc2bb6593b6ab15b6a31454d7ffdb952de17b2a5551ff9b943e3ea897dd0ecf7a6e26e30ef4f0c5e281370dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56f087ccd9a2a43c564c5517a869abcd50688fb3743be0349d5dc73db71a7aea8b16473d826bd1024139186d4dcb62a87e8e796e7a6756e2438762e963e9823a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/155fd947e9b17824e78aadb3f6089cede590a58643500be06df712266dcb43fb989548ef09b98390d4067c2444a8972e3970ab62ae3a79555f266e5d564c9dcf"} build_url:https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011391 build_url: 'https://queue.taskcluster.net/v1/task/KW0JO5OSRC2uahiDReF20Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1865e238dfb7c7f4dbfcdeadf951f2f411c5b7a2bc2bb6593b6ab15b6a31454d7ffdb952de17b2a5551ff9b943e3ea897dd0ecf7a6e26e30ef4f0c5e281370dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56f087ccd9a2a43c564c5517a869abcd50688fb3743be0349d5dc73db71a7aea8b16473d826bd1024139186d4dcb62a87e8e796e7a6756e2438762e963e9823a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/155fd947e9b17824e78aadb3f6089cede590a58643500be06df712266dcb43fb989548ef09b98390d4067c2444a8972e3970ab62ae3a79555f266e5d564c9dcf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 19:21:43.948969) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:21:43.949490) ========= 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.fE9231BAdz/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.dPsc0mCVUS/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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.005395 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 19:21:43.999284) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 19:21:43.999620) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 19:21:43.999980) ========= ========= Total master_lag: 0.19 =========