builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0204 starttime: 1464288683.71 results: success (0) buildid: 20160526094305 builduid: a58aec5c95ac45fa9c911e157ba9245a revision: b0096c5c727749ad3e79cbdf20d2e96bd179c213 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:23.714610) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:23.715122) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:23.715428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:23.736666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:23.737060) ========= 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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-26 11:51:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.2M=0.001s 2016-05-26 11:51:23 (14.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123782 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:23.881583) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:23.882019) ========= 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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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.019729 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:23.931473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 11:51:23.931831) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b0096c5c727749ad3e79cbdf20d2e96bd179c213 --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 b0096c5c727749ad3e79cbdf20d2e96bd179c213 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4DgAZ1ZrwD/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.0WLQABfCii/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-26 11:51:23,254 truncating revision to first 12 chars 2016-05-26 11:51:23,254 Setting DEBUG logging. 2016-05-26 11:51:23,254 attempt 1/10 2016-05-26 11:51:23,254 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b0096c5c7277?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 11:51:24,603 unpacking tar archive at: autoland-b0096c5c7277/testing/mozharness/ program finished with exit code 0 elapsedTime=1.606022 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 11:51:25.559505) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:25.559888) ========= script_repo_revision: b0096c5c727749ad3e79cbdf20d2e96bd179c213 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:25.560286) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:25.560650) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 11:51:25.574670) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-26 11:51:25.575025) ========= /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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:51:24 INFO - MultiFileLogger online at 20160526 11:51:24 in /builds/slave/test 11:51:24 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 11:51:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:51:24 INFO - {'append_to_log': False, 11:51:24 INFO - 'base_work_dir': '/builds/slave/test', 11:51:24 INFO - 'blob_upload_branch': 'Autoland', 11:51:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:51:24 INFO - 'branch': 'Autoland', 11:51:24 INFO - 'buildbot_json_path': 'buildprops.json', 11:51:24 INFO - 'config_files': ('talos/mac_config.py',), 11:51:24 INFO - 'default_actions': ('clobber', 11:51:24 INFO - 'read-buildbot-config', 11:51:24 INFO - 'download-and-extract', 11:51:24 INFO - 'populate-webroot', 11:51:24 INFO - 'create-virtualenv', 11:51:24 INFO - 'install', 11:51:24 INFO - 'run-tests'), 11:51:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:51:24 INFO - 'download_minidump_stackwalk': True, 11:51:24 INFO - 'download_symbols': 'ondemand', 11:51:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:51:24 INFO - 'tooltool.py': '/tools/tooltool.py', 11:51:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:51:24 INFO - '/tools/misc-python/virtualenv.py')}, 11:51:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:51:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:51:24 INFO - 'installer_path': 'installer.exe', 11:51:24 INFO - 'log_level': 'info', 11:51:24 INFO - 'log_name': 'talos', 11:51:24 INFO - 'log_to_console': True, 11:51:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:51:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:51:24 INFO - 'opt_config_files': (), 11:51:24 INFO - 'pip_index': False, 11:51:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:51:24 INFO - 'cmd': ('bash', 11:51:24 INFO - '-c', 11:51:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:51:24 INFO - 'enabled': True, 11:51:24 INFO - 'halt_on_failure': False, 11:51:24 INFO - 'name': 'check_screen_resolution'},), 11:51:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:51:24 INFO - 'cmd': ('bash', 11:51:24 INFO - '-c', 11:51:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:51:24 INFO - 'enabled': True, 11:51:24 INFO - 'halt_on_failure': False, 11:51:24 INFO - 'name': 'check_screen_resolution'},), 11:51:24 INFO - 'run_cmd_checks_enabled': True, 11:51:24 INFO - 'sps_profile': False, 11:51:24 INFO - 'sps_profile_interval': 0, 11:51:24 INFO - 'suite': 'dromaeojs', 11:51:25 INFO - 'system_bits': '32', 11:51:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:51:25 INFO - 'title': 't-yosemite-r7-0204', 11:51:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:51:25 INFO - 'use_talos_json': True, 11:51:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:51:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:51:25 INFO - 'work_dir': 'build'} 11:51:25 INFO - ##### 11:51:25 INFO - ##### Running clobber step. 11:51:25 INFO - ##### 11:51:25 INFO - Running pre-action listener: _resource_record_pre_action 11:51:25 INFO - Running main action method: clobber 11:51:25 INFO - rmtree: /builds/slave/test/build 11:51:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:51:25 INFO - Running post-action listener: _resource_record_post_action 11:51:25 INFO - ##### 11:51:25 INFO - ##### Finished clobber step (success) 11:51:25 INFO - ##### 11:51:25 INFO - ##### 11:51:25 INFO - ##### Running read-buildbot-config step. 11:51:25 INFO - ##### 11:51:25 INFO - Running pre-action listener: _resource_record_pre_action 11:51:25 INFO - Running main action method: read_buildbot_config 11:51:25 INFO - Using buildbot properties: 11:51:25 INFO - { 11:51:25 INFO - "project": "", 11:51:25 INFO - "product": "firefox", 11:51:25 INFO - "script_repo_revision": "production", 11:51:25 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 11:51:25 INFO - "repository": "", 11:51:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 11:51:25 INFO - "buildid": "20160526094305", 11:51:25 INFO - "pgo_build": "False", 11:51:25 INFO - "basedir": "/builds/slave/test", 11:51:25 INFO - "buildnumber": 0, 11:51:25 INFO - "slavename": "t-yosemite-r7-0204", 11:51:25 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:51:25 INFO - "platform": "yosemite_r7", 11:51:25 INFO - "scheduler": "tests-autoland-macosx64-talos", 11:51:25 INFO - "branch": "autoland", 11:51:25 INFO - "repo_path": "integration/autoland", 11:51:25 INFO - "revision": "b0096c5c727749ad3e79cbdf20d2e96bd179c213", 11:51:25 INFO - "stage_platform": "macosx64", 11:51:25 INFO - "builduid": "a58aec5c95ac45fa9c911e157ba9245a", 11:51:25 INFO - "slavebuilddir": "test" 11:51:25 INFO - } 11:51:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:51:25 INFO - Running post-action listener: _resource_record_post_action 11:51:25 INFO - ##### 11:51:25 INFO - ##### Finished read-buildbot-config step (success) 11:51:25 INFO - ##### 11:51:25 INFO - ##### 11:51:25 INFO - ##### Running download-and-extract step. 11:51:25 INFO - ##### 11:51:25 INFO - Running pre-action listener: _resource_record_pre_action 11:51:25 INFO - Running main action method: download_and_extract 11:51:25 INFO - mkdir: /builds/slave/test/build/tests 11:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:25 INFO - https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:51:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:51:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:51:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/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 11:51:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/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 11:51:26 INFO - Downloaded 1199 bytes. 11:51:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:51:26 INFO - Using the following test package requirements: 11:51:26 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:51:26 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:51:26 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:51:26 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:51:26 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:51:26 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:51:26 INFO - u'jsshell-mac.zip'], 11:51:26 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:51:26 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:51:26 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:51:26 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:51:26 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:51:26 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:51:26 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:51:26 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:51:26 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:51:26 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:51:26 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:51:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:51:26 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')]} 11:51:26 INFO - https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:51:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:51:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:51:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/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 11:51:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/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 11:51:27 INFO - Downloaded 18622106 bytes. 11:51:27 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'] 11:51:27 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 11:51:30 INFO - Return code: 0 11:51:30 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 11:51:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:30 INFO - https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:51:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:51:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:51:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/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 11:51:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/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 11:51:31 INFO - Downloaded 18622106 bytes. 11:51:31 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'] 11:51:31 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 11:51:35 INFO - Return code: 0 11:51: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')]} 11:51:35 INFO - https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:51:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:51:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:51:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/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 11:51:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/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 11:51:36 INFO - Downloaded 10834034 bytes. 11:51:36 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'] 11:51:36 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 11:51:36 INFO - Return code: 0 11:51: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')]} 11:51:36 INFO - https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:51:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:51:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:51:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:51:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Znhe8c4hR8yE94UEM1UwDg/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 11:51:39 INFO - Downloaded 110496526 bytes. 11:51:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:51:39 INFO - mkdir: /builds/slave/test/properties 11:51:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:51:39 INFO - Writing to file /builds/slave/test/properties/build_url 11:51:39 INFO - Contents: 11:51:39 INFO - build_url:https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:51:41 INFO - Running post-action listener: _resource_record_post_action 11:51:41 INFO - Running post-action listener: set_extra_try_arguments 11:51:41 INFO - ##### 11:51:41 INFO - ##### Finished download-and-extract step (success) 11:51:41 INFO - ##### 11:51:41 INFO - ##### 11:51:41 INFO - ##### Running populate-webroot step. 11:51:41 INFO - ##### 11:51:41 INFO - Running pre-action listener: _resource_record_pre_action 11:51:41 INFO - Running main action method: populate_webroot 11:51:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:51:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:51:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:51:41 INFO - '--tppagecycles', 11:51:41 INFO - '7'], 11:51:41 INFO - 'tests': ['tsvgm']}}, 11:51:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:51:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:51:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:51:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:51:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:41 INFO - 'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:51:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:51:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:41 INFO - 'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['damp', 'tps']}, 11:51:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:41 INFO - 'tests': ['damp', 'tps']}, 11:51:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['dromaeo_dom']}, 11:51:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:51:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['a11yr', 11:51:41 INFO - 'ts_paint', 11:51:41 INFO - 'tpaint', 11:51:41 INFO - 'sessionrestore', 11:51:41 INFO - 'sessionrestore_no_auto_restore', 11:51:41 INFO - 'tabpaint']}, 11:51:41 INFO - 'other-e10s': {'tests': ['a11yr', 11:51:41 INFO - 'ts_paint', 11:51:41 INFO - 'tpaint', 11:51:41 INFO - 'sessionrestore', 11:51:41 INFO - 'sessionrestore_no_auto_restore', 11:51:41 INFO - 'tabpaint']}, 11:51:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:51:41 INFO - 'ts_paint', 11:51:41 INFO - 'tpaint', 11:51:41 INFO - 'sessionrestore', 11:51:41 INFO - 'sessionrestore_no_auto_restore', 11:51:41 INFO - 'tabpaint']}, 11:51:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:51:41 INFO - 'ts_paint', 11:51:41 INFO - 'tpaint', 11:51:41 INFO - 'sessionrestore', 11:51:41 INFO - 'sessionrestore_no_auto_restore', 11:51:41 INFO - 'tabpaint']}, 11:51:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['a11yr', 11:51:41 INFO - 'ts_paint', 11:51:41 INFO - 'tpaint', 11:51:41 INFO - 'sessionrestore', 11:51:41 INFO - 'sessionrestore_no_auto_restore', 11:51:41 INFO - 'tabpaint']}, 11:51:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['a11yr', 11:51:41 INFO - 'ts_paint', 11:51:41 INFO - 'tpaint', 11:51:41 INFO - 'sessionrestore', 11:51:41 INFO - 'sessionrestore_no_auto_restore', 11:51:41 INFO - 'tabpaint']}, 11:51:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['tsvgx', 11:51:41 INFO - 'tsvgr_opacity', 11:51:41 INFO - 'tart', 11:51:41 INFO - 'tscrollx', 11:51:41 INFO - 'cart']}, 11:51:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:51:41 INFO - 'tsvgr_opacity', 11:51:41 INFO - 'tart', 11:51:41 INFO - 'tscrollx', 11:51:41 INFO - 'cart']}, 11:51:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:41 INFO - 'talos_options': ['--disable-e10s'], 11:51:41 INFO - 'tests': ['tp5o']}, 11:51:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:41 INFO - 'tests': ['tp5o']}, 11:51:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:41 INFO - 'talos_options': ['--disable-e10s', 11:51:41 INFO - '--xperf_path', 11:51:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:51:41 INFO - 'tests': ['tp5n']}, 11:51:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:51:41 INFO - 'talos_options': ['--xperf_path', 11:51:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:51:41 INFO - 'tests': ['tp5n']}}, 11:51:41 INFO - 'talos.zip': {'path': '', 11:51:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:51:41 INFO - Running post-action listener: _resource_record_post_action 11:51:41 INFO - ##### 11:51:41 INFO - ##### Finished populate-webroot step (success) 11:51:41 INFO - ##### 11:51:41 INFO - ##### 11:51:41 INFO - ##### Running create-virtualenv step. 11:51:41 INFO - ##### 11:51:41 INFO - Running pre-action listener: _resource_record_pre_action 11:51:41 INFO - Running main action method: create_virtualenv 11:51:41 INFO - Creating virtualenv /builds/slave/test/build/venv 11:51:41 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 11:51:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:51:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:51:41 INFO - Using real prefix '/tools/python27' 11:51:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:51:41 INFO - Installing distribute.............................................................................................................................................................................................done. 11:51:44 INFO - Installing pip.................done. 11:51:44 INFO - Return code: 0 11:51:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:51:44 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')]} 11:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:44 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')]} 11:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:44 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 0x11046ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0d6d2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, '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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:51:44 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 11:51:44 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 11:51:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:51:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:44 INFO - 'HOME': '/Users/cltbld', 11:51:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:44 INFO - 'LOGNAME': 'cltbld', 11:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:44 INFO - 'MOZ_NO_REMOTE': '1', 11:51:44 INFO - 'NO_EM_RESTART': '1', 11:51:44 INFO - 'PAGER': '/bin/cat', 11:51:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:44 INFO - 'PWD': '/builds/slave/test', 11:51:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:44 INFO - 'SHELL': '/bin/bash', 11:51:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:51:44 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:51:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:44 INFO - 'USER': 'cltbld', 11:51:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:44 INFO - 'XPC_FLAGS': '0x0', 11:51:44 INFO - 'XPC_SERVICE_NAME': '0', 11:51:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:45 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 11:51:45 INFO - Cleaning up... 11:51:45 INFO - Return code: 0 11:51:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:51:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:45 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 0x11046ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0d6d2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, '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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:51:45 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 11:51:45 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 11:51:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:51:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:45 INFO - 'HOME': '/Users/cltbld', 11:51:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:45 INFO - 'LOGNAME': 'cltbld', 11:51:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:45 INFO - 'MOZ_NO_REMOTE': '1', 11:51:45 INFO - 'NO_EM_RESTART': '1', 11:51:45 INFO - 'PAGER': '/bin/cat', 11:51:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:45 INFO - 'PWD': '/builds/slave/test', 11:51:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:45 INFO - 'SHELL': '/bin/bash', 11:51:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:51:45 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:51:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:45 INFO - 'USER': 'cltbld', 11:51:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:45 INFO - 'XPC_FLAGS': '0x0', 11:51:45 INFO - 'XPC_SERVICE_NAME': '0', 11:51:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:45 INFO - Downloading/unpacking psutil>=3.1.1 11:51:45 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 11:51:45 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 11:51:45 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 11:51:45 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 11:51:45 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 11:51:45 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 11:51:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:51:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:51:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:51:48 INFO - Installing collected packages: psutil 11:51:48 INFO - Running setup.py install for psutil 11:51:48 INFO - building 'psutil._psutil_osx' extension 11:51:48 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 11:51:48 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 11:51:48 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 11:51:48 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 11:51:48 INFO - building 'psutil._psutil_posix' extension 11:51:48 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 11:51:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:51:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:51:48 INFO - ^ 11:51:49 INFO - 1 warning generated. 11:51:49 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 11:51:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:51:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:51:49 INFO - Successfully installed psutil 11:51:49 INFO - Cleaning up... 11:51:49 INFO - Return code: 0 11:51:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:51: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')]} 11:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51: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')]} 11:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:49 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 0x11046ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0d6d2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, '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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:51:49 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 11:51:49 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 11:51:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:51:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:49 INFO - 'HOME': '/Users/cltbld', 11:51:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:49 INFO - 'LOGNAME': 'cltbld', 11:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:49 INFO - 'MOZ_NO_REMOTE': '1', 11:51:49 INFO - 'NO_EM_RESTART': '1', 11:51:49 INFO - 'PAGER': '/bin/cat', 11:51:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:49 INFO - 'PWD': '/builds/slave/test', 11:51:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:49 INFO - 'SHELL': '/bin/bash', 11:51:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:51:49 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:51:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:49 INFO - 'USER': 'cltbld', 11:51:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:49 INFO - 'XPC_FLAGS': '0x0', 11:51:49 INFO - 'XPC_SERVICE_NAME': '0', 11:51:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:51:49 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 11:51:49 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 11:51:49 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 11:51:49 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 11:51:49 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 11:51:49 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 11:51:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:51:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:51:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:51:52 INFO - Installing collected packages: mozsystemmonitor 11:51:52 INFO - Running setup.py install for mozsystemmonitor 11:51:52 INFO - Successfully installed mozsystemmonitor 11:51:52 INFO - Cleaning up... 11:51:52 INFO - Return code: 0 11:51:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:51:52 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')]} 11:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:52 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')]} 11:51:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:52 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 0x11046ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0d6d2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, '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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:51:52 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 11:51:52 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 11:51:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:51:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:52 INFO - 'HOME': '/Users/cltbld', 11:51:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:52 INFO - 'LOGNAME': 'cltbld', 11:51:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:52 INFO - 'MOZ_NO_REMOTE': '1', 11:51:52 INFO - 'NO_EM_RESTART': '1', 11:51:52 INFO - 'PAGER': '/bin/cat', 11:51:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:52 INFO - 'PWD': '/builds/slave/test', 11:51:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:52 INFO - 'SHELL': '/bin/bash', 11:51:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:51:52 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:51:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:52 INFO - 'USER': 'cltbld', 11:51:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:52 INFO - 'XPC_FLAGS': '0x0', 11:51:52 INFO - 'XPC_SERVICE_NAME': '0', 11:51:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:52 INFO - Downloading/unpacking blobuploader==1.2.4 11:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:54 INFO - Downloading blobuploader-1.2.4.tar.gz 11:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:51:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:51:55 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 11:51:55 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 11:51:55 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 11:51:55 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 11:51:55 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 11:51:55 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 11:51:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:51:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:51:55 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 11:51:55 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 11:51:55 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 11:51:55 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 11:51:55 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 11:51:55 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 11:51:56 INFO - Downloading docopt-0.6.1.tar.gz 11:51:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:51:56 INFO - Installing collected packages: blobuploader, requests, docopt 11:51:56 INFO - Running setup.py install for blobuploader 11:51:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:51:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:51:56 INFO - Running setup.py install for requests 11:51:56 INFO - Running setup.py install for docopt 11:51:57 INFO - Successfully installed blobuploader requests docopt 11:51:57 INFO - Cleaning up... 11:51:57 INFO - Return code: 0 11:51:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51: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')]} 11:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51: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')]} 11:51:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:57 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 0x11046ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0d6d2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, '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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:51:57 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 11:51:57 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 11:51:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:51:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:57 INFO - 'HOME': '/Users/cltbld', 11:51:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:57 INFO - 'LOGNAME': 'cltbld', 11:51:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:57 INFO - 'MOZ_NO_REMOTE': '1', 11:51:57 INFO - 'NO_EM_RESTART': '1', 11:51:57 INFO - 'PAGER': '/bin/cat', 11:51:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:57 INFO - 'PWD': '/builds/slave/test', 11:51:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:57 INFO - 'SHELL': '/bin/bash', 11:51:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:51:57 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:51:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:57 INFO - 'USER': 'cltbld', 11:51:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:57 INFO - 'XPC_FLAGS': '0x0', 11:51:57 INFO - 'XPC_SERVICE_NAME': '0', 11:51:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:51:57 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-GgK14C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:51:57 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-5yms9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:51:57 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-z2Cc1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:51:57 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-YKBXZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:51:57 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-qYGaeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:51:57 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-xNCr6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-2BOuMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-C3S2dO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-wWk4Ot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-H2vzi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-yl2l8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-2m1y1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-1wDlUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-fd1dnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-5thi14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:51:58 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-7yi5UF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:51:59 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-0Jwu6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:51:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:51:59 INFO - Running setup.py install for manifestparser 11:51:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:51:59 INFO - Running setup.py install for mozcrash 11:51:59 INFO - Running setup.py install for mozdebug 11:51:59 INFO - Running setup.py install for mozdevice 11:51:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:51:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:51:59 INFO - Running setup.py install for mozfile 11:51:59 INFO - Running setup.py install for mozhttpd 11:52:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:52:00 INFO - Running setup.py install for mozinfo 11:52:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:52:00 INFO - Running setup.py install for mozInstall 11:52:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:52:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:52:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:52:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:52:00 INFO - Running setup.py install for mozleak 11:52:00 INFO - Running setup.py install for mozlog 11:52:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:52:00 INFO - Running setup.py install for moznetwork 11:52:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:52:00 INFO - Running setup.py install for mozprocess 11:52:00 INFO - Running setup.py install for mozprofile 11:52:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:52:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:52:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:52:01 INFO - Running setup.py install for mozrunner 11:52:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:52:01 INFO - Running setup.py install for mozscreenshot 11:52:01 INFO - Running setup.py install for moztest 11:52:01 INFO - Running setup.py install for mozversion 11:52:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:52:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:52:01 INFO - Cleaning up... 11:52:01 INFO - Return code: 0 11:52:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52: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')]} 11:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52: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')]} 11:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:01 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 0x11046ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0d6d2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, '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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:52:01 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 11:52:01 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 11:52:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:52:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:01 INFO - 'HOME': '/Users/cltbld', 11:52:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:01 INFO - 'LOGNAME': 'cltbld', 11:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:01 INFO - 'MOZ_NO_REMOTE': '1', 11:52:01 INFO - 'NO_EM_RESTART': '1', 11:52:01 INFO - 'PAGER': '/bin/cat', 11:52:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:01 INFO - 'PWD': '/builds/slave/test', 11:52:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:01 INFO - 'SHELL': '/bin/bash', 11:52:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:52:01 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:52:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:01 INFO - 'USER': 'cltbld', 11:52:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:01 INFO - 'XPC_FLAGS': '0x0', 11:52:01 INFO - 'XPC_SERVICE_NAME': '0', 11:52:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-DSrSHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:02 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)) 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-i9W817-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:02 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)) 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-ZP12Bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:02 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)) 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-1wjO7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:02 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)) 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-EXU_xV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:02 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)) 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-Xh8JZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:02 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)) 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-wYUagf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:02 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)) 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-cuuVtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:02 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)) 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-R6ffzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:02 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)) 11:52:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:02 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-lSbcST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:03 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)) 11:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:03 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-QgRelH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:03 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)) 11:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:03 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-2_abHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:03 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)) 11:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:03 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-UlV8r_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:03 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)) 11:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:03 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-HMCWX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:03 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)) 11:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:03 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-qe9xOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:03 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)) 11:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:03 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-q9jvgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:03 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)) 11:52:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:03 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-ndHWIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:03 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)) 11:52:03 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)) 11:52:03 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)) 11:52:03 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)) 11:52:03 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)) 11:52:03 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)) 11:52:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:52:03 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 11:52:03 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 11:52:03 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 11:52:03 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 11:52:03 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 11:52:03 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 11:52:06 INFO - Downloading blessings-1.6.tar.gz 11:52:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:52:06 INFO - Installing collected packages: blessings 11:52:06 INFO - Running setup.py install for blessings 11:52:06 INFO - Successfully installed blessings 11:52:06 INFO - Cleaning up... 11:52:06 INFO - Return code: 0 11:52:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:52:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:06 INFO - Reading from file tmpfile_stdout 11:52:06 INFO - Current package versions: 11:52:06 INFO - blessings == 1.6 11:52:06 INFO - blobuploader == 1.2.4 11:52:06 INFO - docopt == 0.6.1 11:52:06 INFO - manifestparser == 1.1 11:52:06 INFO - mozInstall == 1.12 11:52:06 INFO - mozcrash == 0.17 11:52:06 INFO - mozdebug == 0.1 11:52:06 INFO - mozdevice == 0.48 11:52:06 INFO - mozfile == 1.2 11:52:06 INFO - mozhttpd == 0.7 11:52:06 INFO - mozinfo == 0.9 11:52:06 INFO - mozleak == 0.1 11:52:06 INFO - mozlog == 3.2 11:52:06 INFO - moznetwork == 0.27 11:52:06 INFO - mozprocess == 0.22 11:52:06 INFO - mozprofile == 0.28 11:52:06 INFO - mozrunner == 6.11 11:52:06 INFO - mozscreenshot == 0.1 11:52:06 INFO - mozsystemmonitor == 0.3 11:52:06 INFO - moztest == 0.7 11:52:06 INFO - mozversion == 1.4 11:52:06 INFO - psutil == 3.1.1 11:52:06 INFO - requests == 1.2.3 11:52:06 INFO - wsgiref == 0.1.2 11:52:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:06 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')]} 11:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:06 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')]} 11:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:06 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 0x11046ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0d6d2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, '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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:52:06 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 11:52:06 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 11:52:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:52:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:06 INFO - 'HOME': '/Users/cltbld', 11:52:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:06 INFO - 'LOGNAME': 'cltbld', 11:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:06 INFO - 'MOZ_NO_REMOTE': '1', 11:52:06 INFO - 'NO_EM_RESTART': '1', 11:52:06 INFO - 'PAGER': '/bin/cat', 11:52:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:06 INFO - 'PWD': '/builds/slave/test', 11:52:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:06 INFO - 'SHELL': '/bin/bash', 11:52:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:52:06 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:52:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:06 INFO - 'USER': 'cltbld', 11:52:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:06 INFO - 'XPC_FLAGS': '0x0', 11:52:06 INFO - 'XPC_SERVICE_NAME': '0', 11:52:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:07 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)) 11:52:07 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)) 11:52:07 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)) 11:52:07 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)) 11:52:07 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)) 11:52:07 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)) 11:52:07 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)) 11:52:07 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)) 11:52:07 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)) 11:52:07 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)) 11:52:07 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)) 11:52:07 INFO - Cleaning up... 11:52:07 INFO - Return code: 0 11:52:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:07 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 0x11046ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0d6d2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, '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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:52:07 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 11:52:07 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 11:52:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:52:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:07 INFO - 'HOME': '/Users/cltbld', 11:52:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:07 INFO - 'LOGNAME': 'cltbld', 11:52:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:07 INFO - 'MOZ_NO_REMOTE': '1', 11:52:07 INFO - 'NO_EM_RESTART': '1', 11:52:07 INFO - 'PAGER': '/bin/cat', 11:52:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:07 INFO - 'PWD': '/builds/slave/test', 11:52:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:07 INFO - 'SHELL': '/bin/bash', 11:52:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:52:07 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:52:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:07 INFO - 'USER': 'cltbld', 11:52:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:07 INFO - 'XPC_FLAGS': '0x0', 11:52:07 INFO - 'XPC_SERVICE_NAME': '0', 11:52:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:07 INFO - Downloading/unpacking jsonschema 11:52: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 11:52: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 11:52: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 11:52: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 11:52: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 11:52: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 11:52:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:52:09 INFO - Downloading/unpacking functools32 (from jsonschema) 11:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:10 INFO - Downloading functools32-3.2.3-2.tar.gz 11:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:52:10 INFO - warning: no files found matching '*.txt' 11:52:10 INFO - no previously-included directories found matching 'build' 11:52:10 INFO - no previously-included directories found matching 'dist' 11:52:10 INFO - no previously-included directories found matching '.git*' 11:52:10 INFO - Installing collected packages: jsonschema, functools32 11:52:10 INFO - Running setup.py install for functools32 11:52:10 INFO - warning: no files found matching '*.txt' 11:52:10 INFO - no previously-included directories found matching 'build' 11:52:10 INFO - no previously-included directories found matching 'dist' 11:52:10 INFO - no previously-included directories found matching '.git*' 11:52:10 INFO - Successfully installed jsonschema functools32 11:52:10 INFO - Cleaning up... 11:52:10 INFO - Return code: 0 11:52:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:52:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:52:10 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 0x11046ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110472570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0d6d2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, '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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 11:52:10 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 11:52:10 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 11:52:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:52:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:10 INFO - 'HOME': '/Users/cltbld', 11:52:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:10 INFO - 'LOGNAME': 'cltbld', 11:52:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:10 INFO - 'MOZ_NO_REMOTE': '1', 11:52:10 INFO - 'NO_EM_RESTART': '1', 11:52:10 INFO - 'PAGER': '/bin/cat', 11:52:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:10 INFO - 'PWD': '/builds/slave/test', 11:52:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:10 INFO - 'SHELL': '/bin/bash', 11:52:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:52:10 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:52:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:10 INFO - 'USER': 'cltbld', 11:52:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:10 INFO - 'XPC_FLAGS': '0x0', 11:52:10 INFO - 'XPC_SERVICE_NAME': '0', 11:52:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:10 INFO - Downloading/unpacking flake8 11:52:10 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 11:52:10 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 11:52:10 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 11:52:10 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 11:52:10 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 11:52:10 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 11:52:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:52:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:52:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:52:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:52:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:52:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:52:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:52:14 INFO - no previously-included directories found matching 'docs/_build' 11:52:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:52:14 INFO - Running setup.py install for pep8 11:52:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:52:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:52:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:52:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:52:14 INFO - no previously-included directories found matching 'docs/_build' 11:52:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:52:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:52:14 INFO - Cleaning up... 11:52:14 INFO - Return code: 0 11:52:14 INFO - Running post-action listener: _resource_record_post_action 11:52:14 INFO - Running post-action listener: _start_resource_monitoring 11:52:14 INFO - Starting resource monitoring. 11:52:14 INFO - ##### 11:52:14 INFO - ##### Finished create-virtualenv step (success) 11:52:14 INFO - ##### 11:52:14 INFO - ##### 11:52:14 INFO - ##### Running install step. 11:52:14 INFO - ##### 11:52:14 INFO - Running pre-action listener: _resource_record_pre_action 11:52:14 INFO - Running main action method: install 11:52:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:15 INFO - Reading from file tmpfile_stdout 11:52:15 INFO - Detecting whether we're running mozinstall >=1.0... 11:52:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:52:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:52:15 INFO - Reading from file tmpfile_stdout 11:52:15 INFO - Output received: 11:52:15 INFO - Usage: mozinstall [options] installer 11:52:15 INFO - Options: 11:52:15 INFO - -h, --help show this help message and exit 11:52:15 INFO - -d DEST, --destination=DEST 11:52:15 INFO - Directory to install application into. [default: 11:52:15 INFO - "/builds/slave/test"] 11:52:15 INFO - --app=APP Application being installed. [default: firefox] 11:52:15 INFO - mkdir: /builds/slave/test/build/application 11:52:15 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'] 11:52:15 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 11:52:48 INFO - Reading from file tmpfile_stdout 11:52:48 INFO - Output received: 11:52:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:52:48 INFO - Running post-action listener: _resource_record_post_action 11:52:48 INFO - ##### 11:52:48 INFO - ##### Finished install step (success) 11:52:48 INFO - ##### 11:52:48 INFO - ##### 11:52:48 INFO - ##### Running run-tests step. 11:52:48 INFO - ##### 11:52:48 INFO - Running pre-action listener: _resource_record_pre_action 11:52:48 INFO - Running main action method: run_tests 11:52:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:52:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:52:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:52:48 INFO - 2016-05-26 11:52:48.662 screenresolution[2200:19574] starting screenresolution argv=screenresolution get 11:52:48 INFO - 2016-05-26 11:52:48.664 screenresolution[2200:19574] Display 0: 1600x1200x32@60 11:52:48 INFO - 2016-05-26 11:52:48.670 screenresolution[2201:19577] starting screenresolution argv=screenresolution list 11:52:48 INFO - Available Modes on Display 0 11:52:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:52:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:52:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:52:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:52:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:52:48 INFO - Intel Iris: 11:52:48 INFO - Chipset Model: Intel Iris 11:52:48 INFO - Type: GPU 11:52:48 INFO - Bus: Built-In 11:52:48 INFO - VRAM (Dynamic, Max): 1536 MB 11:52:48 INFO - Vendor: Intel (0x8086) 11:52:48 INFO - Device ID: 0x0a2e 11:52:48 INFO - Revision ID: 0x0009 11:52:48 INFO - Displays: 11:52:48 INFO - Display: 11:52:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:52:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:52:48 INFO - Display Serial Number: 1600x1200 60 11:52:48 INFO - Main Display: Yes 11:52:48 INFO - Mirror: Off 11:52:48 INFO - Online: Yes 11:52:48 INFO - Rotation: Supported 11:52:48 INFO - Return code: 0 11:52:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:52:48 INFO - Python 2.7.3 11:52:48 INFO - Return code: 0 11:52:48 INFO - grabbing minidump binary from tooltool 11:52:48 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')]} 11:52:48 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 0x110310d30>, '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 0x110134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110307b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:52:48 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 11:52:48 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 11:52:48 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 11:52:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:52:49 INFO - Return code: 0 11:52:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:52:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:52:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:52:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:52:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:52:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:52:50 INFO - Return code: 0 11:52:50 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/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0204', '--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 11:52:50 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/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0204 --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 11:52:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DgAZ1ZrwD/Render', 11:52:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:50 INFO - 'HOME': '/Users/cltbld', 11:52:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:50 INFO - 'LOGNAME': 'cltbld', 11:52:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:52:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:52:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:50 INFO - 'MOZ_NO_REMOTE': '1', 11:52:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:52:50 INFO - 'NO_EM_RESTART': '1', 11:52:50 INFO - 'PAGER': '/bin/cat', 11:52:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:50 INFO - 'PWD': '/builds/slave/test', 11:52:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:52:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:50 INFO - 'SHELL': '/bin/bash', 11:52:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0WLQABfCii/Listeners', 11:52:50 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 11:52:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:50 INFO - 'USER': 'cltbld', 11:52:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:50 INFO - 'XPC_FLAGS': '0x0', 11:52:50 INFO - 'XPC_SERVICE_NAME': '0', 11:52:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:50 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/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0204', '--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 11:52:50 INFO - mozversion application_buildid: 20160526094305 11:52:50 INFO - mozversion application_changeset: b0096c5c727749ad3e79cbdf20d2e96bd179c213 11:52:50 INFO - mozversion application_display_name: Nightly 11:52:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:52:50 INFO - mozversion application_name: Firefox 11:52:50 INFO - mozversion application_remotingname: firefox 11:52:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:52:50 INFO - mozversion application_vendor: Mozilla 11:52:50 INFO - mozversion application_version: 49.0a1 11:52:50 INFO - mozversion platform_buildid: 20160526094305 11:52:50 INFO - mozversion platform_changeset: b0096c5c727749ad3e79cbdf20d2e96bd179c213 11:52:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:52:50 INFO - mozversion platform_version: 49.0a1 11:52:50 INFO - using testdate: 1464288770 11:52:50 INFO - actual date: 1464288770 11:52:50 INFO - starting webserver on 'localhost:54828' 11:52:50 INFO - SUITE-START | Running 2 tests 11:52:50 INFO - TEST-START | dromaeo_css 11:52:50 INFO - operating with platform_type : mac_ 11:52:50 INFO - Initialising browser for dromaeo_css test... 11:52:50 INFO - TEST-INFO | started process 2219 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpjLuhNI/profile http://localhost:54828/getInfo.html) 11:52:52 INFO - PROCESS | 2219 | __metrics Screen width/height:1600/1200 11:52:52 INFO - PROCESS | 2219 | colorDepth:24 11:52:52 INFO - PROCESS | 2219 | Browser inner width/height: 1024/689 11:52:52 INFO - PROCESS | 2219 | __metrics 11:52:52 INFO - PROCESS | 2219 | 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" 11:52:52 ERROR - PROCESS | 2219 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:52:53 INFO - TEST-INFO | 2219: exit 0 11:52:53 INFO - Browser initialized. 11:52:53 INFO - Running cycle 1/1 for dromaeo_css test... 11:52:53 INFO - TEST-INFO | started process 2221 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpjLuhNI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:53:05 INFO - PROCESS | 2221 | RSS: Main: 241750016 11:53:05 INFO - PROCESS | 2221 | 11:53:56 INFO - PROCESS | 2221 | 1464288836505 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] 11:56:06 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:54828/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54828/tests/dromaeo/cssquery-ext.html) 11:56:08 INFO - PROCESS | 2221 | RSS: Main: 370143232 11:56:08 INFO - PROCESS | 2221 | 11:59:11 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:54828/tests/dromaeo/cssquery-ext.html (next: http://localhost:54828/tests/dromaeo/cssquery-jquery.html) 11:59:11 INFO - PROCESS | 2221 | RSS: Main: 376315904 11:59:11 INFO - PROCESS | 2221 | 12:02:14 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:54828/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54828/tests/dromaeo/cssquery-mootools.html) 12:02:14 INFO - PROCESS | 2221 | RSS: Main: 371830784 12:02:14 INFO - PROCESS | 2221 | 12:05:17 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:54828/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54828/tests/dromaeo/cssquery-prototype.html) 12:05:17 INFO - PROCESS | 2221 | RSS: Main: 372588544 12:05:17 INFO - PROCESS | 2221 | 12:08:21 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:54828/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54828/tests/dromaeo/cssquery-yui.html) 12:08:21 INFO - PROCESS | 2221 | RSS: Main: 340668416 12:08:21 INFO - PROCESS | 2221 | 12:11:25 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:54828/tests/dromaeo/cssquery-yui.html (next: http://localhost:54828/tests/dromaeo/cssquery-dojo.html) 12:11:25 INFO - PROCESS | 2221 | RSS: Main: 379228160 12:11:25 INFO - PROCESS | 2221 | 12:11:25 INFO - PROCESS | 2221 | __start_tp_report 12:11:25 INFO - PROCESS | 2221 | _x_x_mozilla_page_load 12:11:25 INFO - PROCESS | 2221 | _x_x_mozilla_page_load_details 12:11:25 INFO - PROCESS | 2221 | |i|pagename|runs| 12:11:25 INFO - PROCESS | 2221 | |0;dojo.html;2892.2335581071006;3360.1935535471484;3409.5567576215094;3434.433318502447;3462.792232466052;5652.406497317779;5664.915026274599;5700.002499562581;5788.855278618036;5799.768009279624;5671.574631902603;5690.2887139107615;5705.058665320227;5811.767529298823;5821.767129314822;5059.190529515278;5136.691798492103;5182.352205974254;5207.687538747668;5282.788688452457;5278.762455689484;5297.046531624306;5305.336832895888;5305.814296499623;5353.384360798519;5096.261042148886;5162.1224391853475;5187.662801917863;5250.68495890244;5256.553192978601;4336.241157797404;5158.97420512895;5179.326687530634;5238.8690282742855;5291.523762861322;5121;5133.076046311662;5189.2475591039465;5195.246689230078;5275.235090911796;5710.885782284373;5728.398518155617;5732.656040637575;5791.73937172824;5817.0692689169955;6098.140067279478;6126.151269885583;6167.599106058092;6170.074488826712;6250.749970001195;6230.096635987802;6253.781117660906;6288.717007734663;6290.748370065192;6293.685315734195;5742.799002034909;5883.23517942669;5888.764449421975;5893.96855550277;5911.29064512257;6176.289726681489;6209.59637623553;6217.067439884054;6232.189815323979;6261.342559031327;6157;6171.413715697002;6196.225471816023;6205.441510264098;6208.844778880564;6124.350421260821;6212.968935155295;6291.748330066746;6301.936980630135;6303.086052522359;6100.066499260575;6183.165272688129;6184.165137706352;6191.938080619136;6205.22434695663;6109.951287404261;6212.1303017577575;6225.128482012522;6269.059641053878;6300.243970723543;4968.254761785761;4969.950300497021;4977.601791856643;4984.476629953875;5017.2223305387;4419.712718673276;4467.15124126415;4507.301368287921;4527.524609915911;4534.115847409789;5660.462256085665;5736.426357364314;5747.798827041117;5749.798757043483;5759.913601295984;6264;6266.686665666698;6270.592411493239;6279.560430769893;6287.434130928238;5720.914186287209;5750.396208398058;5757.510611598061;5759.769609215585;5782.421757824184;5451.209574611738;5456.154296084113;5461.044317244467;5469.425710300361;5476.479734425223;6168.629882207082;6217.502599792097;6297.4647154992335;6314.652694101868;6327.873442531123;6216.937830621636;6278.246610406781;6294.559380843297;6296.37036296385;6303.9684801575695;6286.748530058747;6297.590656607489;6341.270753863365;6347.5239357047485;6350.555461117678;6105.8168254952425;6150.261968563801;6156.922538555825;6247.875042499216;6283.528735344776;6266.248050234001;6301.590396624205;6307.495400367868;6309.652969086745;6330.556861019684;6205.751769929333;6217.813465596039;6222.657753823402;6229.56393052482;6234.220722409699;6019.006863867413;6080.7567697291615;6116.457510225689;6137.60105593135;6175.660338681236;4722.622190224705;6049.637021778707;6089.208402907594;6094.573379863367;6117.235345581802;6180.227471566054;6189.102580125947;6197.03945888388;6200.503959683124;6203.658798766111;5797.797077102281;5927.911081333697;5949.434803693643;6019.759209631741;6022.6386416813375 12:11:25 INFO - PROCESS | 2221 | |1;ext.html;513.9948600513995;522.091560684409;529.7060131626948;534.3587694766277;565.5560385097698;30321.84839075804;31008.68991310086;42767.786161069154;43220.78389608047;43326.56673433265;39316.41025384622;41145.382819257735;42875;44942.32586511205;44951.32573011408;9613.038696130383;9845.60617575296;9847.359921605108;9850.40897546146;9866.358686685378;9587.472689002103;9758;9763.755906102333;9777.266704997119;9789.510524473784;9733.2213422926;9795.461249631304;9808.950955245215;9810.117089461946;9818.852717209247;9683.951580242088;9748.756281093029;9776.266779991494;9782.413055216639;9815;9670.854937175947;9766.169875560587;9780.0708932652;9783.951080244553;9786.217102631843;41239.793801030806;41646.37530437046;41730.582694172976;41746;41758.58241417578;3093.8607762650736;3111.6110486189227;3260.02199340197;3283.015095471349;3327.667233276677;29207.269818254503;29660.957707095247;30148.24629384261;30231.848840755876;30234.395312093635;2704.7700945419665;2715.945681086387;2826.1662809471254;2855.6430446194227;2859.642544681915;516240;518931.67478276533;519732;520193;520971;34072.31855362929;35012.29975400529;58801.705991470626;60420.09369859456;60568.69715651393;30860;36038.639613603526;36122.45816312757;36148.638513614525;36201.27597448036;15079.019863708823;15247.313870875838;15262.236888155549;15273.472652734637;15278.388864445298;12757.872421275853;12840.165389249856;12855.035872309614;12872.613821585368;12903.032272579594;6754.662266886636;6821.69302381394;7908.723194688158;7953.5625540594665;7967.721129760431;3241.6272128705027;3284.4088064148204;3288.720458761032;3293.357795229955;3326.8170250635962;845.5856630251133;959.2182371367313;1040.7190058684114;1077;1081.929674571166;29405.852970735436;29440.5653738464;29560.704392956224;29601.25996850053;30415.39169216603;923.930705197113;1075.2849355178719;1075.408525311076;1098.181854518373;1103.0403548912336;12653.87346126527;14786.778198327034;14852.886033547527;15073.397064117313;15211.543653690407;709560;718899;721206;721618;723500;59260.40739592635;61643;61651;61788.69105654443;61867.38132618706;1293.5213970830664;1342.9731405372033;1394.776835706282;1438.7122575484946;1472.889533285008;28933.276668083465;29422.264443389085;29424.411511769216;30157.396852062397;30199.69800301969;41438.79280603577;50402.747986259834;50661.746691267785;50671;50694;551669;552901;553273;554249;556309;23022.654660180448;25195.748042520074;25909.6113558293;26170.738292617592;27079.72920270772;46399;46823.76588117037;47051;47332;48373;36992;38160.427593586675;38335.80832095822;39004.80497597494;39121.21757564776;37112.44331334978;37552.62447375491;37670;38186.61813381831;38617.42073868838;37328.813355933045;37877.621223787406;38320.041998949135;38506;38756;31118.066458006295;32807.34385312328;33011.504827427125;33248.66751332456;33327.83336083304;24131.914063866818;24541;24737.381565461008;24822.131225407025;24852 12:11:25 INFO - PROCESS | 2221 | |2;jquery.html;383.5091083413231;388.9611038896111;389.28371795895555;401.43999121225875;408.4853085112757;19949.90275534845;22026;22356.441088972784;22465.438364040885;22490.875456227204;20873.060712267947;21276.255331063418;21506.89246553769;21918.123275068978;21930;3855.6722678572355;5311.256424100629;5323.068463018975;5353.08997470429;5354.839354819362;3807.1243613968804;4027.697922655798;5222.268882356473;7229.096362954604;7655.234476552356;7423.777286681381;7613.885791713129;7644.35023023041;7706;7726.8454630907345;7490.962545187239;7615.5430674159725;7633.08402991639;7635.236476352348;7636.236376362347;4118.711690181688;4617.053504031699;4632.930506042412;4637.234856248715;6808.387245147911;21801.890990545104;22921.96851141686;27896.721032789614;29203.56194657082;29950.55074173889;2362.988185059081;2378.393509655044;2381.4403615150427;2412.9396765080837;2650.642163307948;15546.922265388714;15947;16466.67066658672;16781.916090419592;16878.071706056162;1747.2399506214838;1792.471220989798;1822.371281907753;1845.1696736468643;1858.8417142429864;119592;123858;132642;133770.33114834363;138185;16171.514854554382;17838.648405478303;19127.56543259261;19747.407577772403;21717.43933773925;35759.106022349646;35811;35831.283374332364;35890.820545897106;35938.10154746152;8123.75628731139;11064;11258.324500529994;11361.488733007034;11459.025982791389;9167.495787731605;9173.724788256362;9201.585928633094;9202;9304.97645259021;8083.919160808434;9853;9873.703788886345;9950.502474876228;9972.900270997197;3679.5952445231005;3806.6764325032127;3819.923601527954;3869.5163104611925;3895.902602434962;811.9066307374609;838.6813011055759;842.2882664148784;903.13299232736;907;20226.98865056741;20580.382588522367;20803.583928321652;20882.79117208839;20930.686039709417;906.7869050773063;915.3775432705824;930.237205491504;938.2681508423441;1008.1330056151704;8888;9340.626374944926;10847;11059.447027648584;11076.446177691083;521776.30230337696;593093;600371;614454;615330;132184.33907830398;134259;134846.32576837248;136787.63212367948;143518;998.381003777648;1020.4999550220339;1022.544967489475;1061.6549621373085;1072.7371793910386;16136.354545818036;18459.261629534667;18852.151653175915;18860.528486787392;18927.242910283436;53102.734486327325;54501.45498545122;55214.72392638011;55511.72244138754;56071.719641401534;80409.19590804251;82093;83232;84614.7307790396;85257;7692.8461430772195;8041.557714325768;8134.674613015413;8184.2224988625985;8445.957770211355;9125.817483650422;11979.580714674945;12846.807297890353;12846.807297890726;12892.806607901077;9312.115349041831;11211.327320360804;11262.605808796652;11565.768684626428;11568.652940411801;4741.739204343794;10434.426106564211;11177.441127943408;11319.434028298718;11416.543338266376;10996.560137594406;11467.770644586895;11704.88295117072;11725.41372931333;11824.172307938361;8136.1863813618165;8221.753347399588;8239.917600823916;8243.917560824315;8600.010998734975;10437.843432348369;11221.775564488828;11492;11532.711682208012;11566 12:11:25 INFO - PROCESS | 2221 | |3;mootools.html;1419.701862608852;1604.4224079331443;1605.1412494315543;1854.4622059602716;1982.454824923146;7811.531308121516;8479.236868681817;8728.170823771747;8732.30141588673;8736.781580460483;8212.178782121784;8545.529995850226;8553.529555874424;8615.698450554217;8653.30773538117;2093.2150443583655;2162.167565487283;2166.0577648722856;2326.906923723049;2393.4255778613096;2188.485705859122;2195.692603035576;2339.894704738282;2403.831731778776;2406.578848701479;2366.2073205476186;2376;2376.8098552115875;2379.5597814404305;2385.2844146755992;2254.7745225477483;2291.049214575957;2295.885205739723;2296.196331284054;2299.9425014374606;2115.936521904345;2304.6082166031647;2365.113082594027;2366.4557151854997;2380.571497130516;8605.225529702357;8681.739547813575;8691.21779039883;8698;8700.521471319074;5156.303898973628;5315.388730295988;5318.654287471302;5321.148616221425;5337.172738225581;3462.0998540379433;3466.6706662866986;3489.5114683944266;3555.2178520725397;3571.08937221007;977.8826540815147;1018.6180182431589;1025.753819083422;1026.8151732688113;1036.6889933020138;11446.198766086378;11698.947094761512;11717.472713727902;11731.589394371242;11757.235779674294;4645.094206629742;4688.179568575554;4801.663883528119;4802.447718512346;4811.230203167476;10181.592736290466;10191.286609937233;10202.693919182437;10206.387616743019;10217.386956782468;4793.880152996133;4924.901501969941;5267.868303292448;5270.631055826132;5283.550898173621;4693.20215563355;4786.7845946931775;4900;4907.435644900811;4909.533594308535;3788.9242215156096;3804.2771873343977;3845.8653947112275;3882.7087968402484;3895.25990061893;1223.9449224784905;1254.5044707340548;1258.1318889965894;1269.8793614606598;1272.166730791333;747.3722073458321;790.9129995700468;810.9716159934374;833.295864994079;844.3203221406703;5661.2923384576925;5756.5682573807135;5803.970980145073;5895.498882594941;5984.820455386345;921.2445794448488;965.6716716316346;966.5988614724951;968.7868668892833;1012.8379459286626;3376.4935259710746;3816.236752649426;3881.3983832506005;3950.6246906543834;3970.404439334065;10306.639267625596;10687.198460115522;10716.410597417062;10716.464176791129;10774.084202842847;10034.899651003543;10524.947505249567;10681.679549613524;10686.519106640064;10707.892921070845;7960.2039796019935;8211.3430925527;8256.25693687559;8257.463264887763;8275.213854683796;5743.1385292206505;5769.653820770767;5874.471297583238;5878.647281163144;6117.908231376622;9626.566804493674;9961.800763984824;9992.300538962494;10006.649767258112;10008.84986725185;9279.350445468752;9644.9035509644;10053.296269261082;10126.443045632266;10208.846867297145;7580.507267027626;7959.641816118405;8180.631871565913;8224.013118425828;8286.70996515119;8121.106678265268;8201.876971845546;8201.95899020501;8219.753407397788;8249.83500330002;7338.412926965723;7384.926150738639;7457.179710231979;7461.291177338144;7529.962350188214;7193.2087470379265;7266.2007179211305;7316.304951029643;7368.484206105735;7434.814129646586;6745.561538499982;6936.79189624312;6975.058367120577;7030.683619237045;7042.7887163385185;4131.380292956081;4710.69380490267;4822.614190864792;4868.148074087091;5023.6985780853265;4841.05599408112;4880.755962201947;4895.849475373356;4929.309896614405;4938.234573641092 12:11:25 INFO - PROCESS | 2221 | |4;prototype.html;270.89976708617814;307.15379130495484;315.3094722557598;323.09694404140424;326.9002954098999;4436.312371582402;4863.878403039926;5038.395392552894;5278.334441107236;5280.392754833195;5104.672785075879;5174.741262936857;5234.345706786652;5241.371035475749;5265.262863199156;3586.7668601540945;3737.0103916866533;3762.398016317383;3810.3522401191726;3946.4080387941754;3930.6242815014803;3991.8802435926823;3995.1410446753944;3999.9200015999663;4018.4374187613757;3281.7210537104256;3476.774009689375;3951.7628942263414;3970.6698256084246;3996.900077498057;3565.8573657053685;3877.034068937265;3890.3580909149814;3970.2258059678375;3993.8402463901407;2956.314900506712;2986.5669477925794;3300.8019518828946;3360.6807353301397;3421.426143973775;4813.494583068762;5009.7495125243595;5063.949360506384;5068.670536415121;5091.949080509185;4233.957660423387;4536.7504787236685;4543.023784653653;4543.091901401419;4546.4544254689645;4613.677042607019;4873.902521949577;5236.554892834114;5323.387810401815;5488;3782.4893639358884;3793.146542028049;3800.5819359870393;3808.333541630231;3836.040989752562;5548.889022219573;5726.3700992890745;5726.742296596622;5765.913511297334;5808.912866307009;3636.9272614547563;3793.1655035892613;3802.7908465034134;3817.0457385653585;3820.3505404081375;3876.2441323942126;3880.301545721797;3897.785621790829;3899.4930659014713;3903.2388684206308;4035.6973227008093;4391.429114215131;4504.864854054384;4554.863354099382;4563.634909207255;4724.834630787906;5141.665791723526;5203.725087353622;5395.3525576931015;5600.663960162322;1130.722283819279;1309.5351150341683;1320.4748515464653;1332.5868980615846;1341.161773891318;716.9426445884317;742.3244847189032;772.5673622771266;800.8678568036325;821.5892053973014;4958.628102892226;4967.584238492084;5113.233015047773;5127.9230811537855;5145.9742701287005;4628.560286772751;5002.699838009731;5545.833624991256;5675.829725108253;5748.310202775694;4803.3995750531185;5011;5106.719130447861;5126.538611524906;5191.97404012985;2276.635738281867;2451.105346548497;2824.3645179834375;2918.6720042380666;2969.8663560139844;5216.686998780086;5284.630075894727;5326.121190003683;5849.766009359663;5880.911786323209;5379.354477462652;5382.542483888835;5784.421557844266;5805.912911306334;6028.613418913675;4766.642501812378;4784.827717209306;5856.912146317809;5861.208736820475;5892.705364731832;4623.514530974199;5465.371482279589;5642.238297829905;5645.266115404971;5665.943340566541;4458.665600079942;4628.652851036119;5415;5566.276384070045;5630.577706671934;5466.298982874804;5493.725313734377;5866.530677545701;5870.853228669317;5886.23478947734;3756.0985363513105;3768.4724138620068;3777.5089238399933;3779.603141670085;3781.905452363713;4983.377077865267;5017.749112544285;5301.602379821452;5660.688662123458;5744.253247077853;4454.331850222363;4658.228354129287;4885.267209918541;5343.759530821045;5497.752601132878;4532.3654688342995;5087.898242035212;5441.619086663895;5462.552423607826;5473.31583552056;5352.5450336721815;5379.4351593082165;5379.838604841861;5384.784608615611;5389.787297857951;5251.553617942518;5398.272552783072;5401.514583489572;5514.80698175578;5515.61390702647;5374.381946076095;5379.193121031876;5380.009396523312;5381.381141168659;5404.378496472956 12:11:25 INFO - PROCESS | 2221 | |5;yui.html;707.2538471912134;709.8296408861873;728.2244409703666;733.8092096055027;749.1197842535026;13546.458141674333;14965;15255.389784408624;15685.686286274267;15697;14239.501617443391;14339.21134337611;14397.064190827588;14411.78382324266;14415.06302090366;727.4725823777745;746.3767753925465;906.4017748286133;907.6142639378271;912.8402529557334;742.3096520236178;868.8349213649386;901.0538934119181;903.7650210945136;905.226031742857;964.7877466957293;972.027972027972;972.7227740094074;978.6330126202674;979.9265055120894;756.9697212111508;936.550455781222;971.917387022104;973.3721749471622;975.3855071305068;727.1456039154026;728.5774250934445;730.4521608793405;730.8720973829612;732.8351120997786;14941.103533788008;15010.324535395934;15029.699406011929;15035.323410446555;15051.247437628075;12316.568920087844;12372.505099795997;12492.437840297118;12529.624111276677;12745.426455809511;10937.2890762101;10948.3978381189;11031.282966607145;11083.113350931906;11487.970425432492;4008.67930565557;4025.0742329264435;4046.4739583854207;4119.876403707894;4153.688473364479;78952;79323;86775.69836452522;87304.56347718285;87493.56253218693;9411;9542.141207291239;9586.185174260127;9730.464824434725;9817.01829817027;21991.120355185612;22026;22031.228906988177;22041.228557000428;22073;7048.682809273595;7390.113186417557;7403.444741644398;7559.848803024019;7789.260020298063;4052.392141178788;4070.816813243411;4075.286824805647;4082;4086.325756250245;3323.21904352477;3457.8098204598987;3461.1693193633346;3461.913452163716;3461.9653803462147;610.1914962674474;613.6808859393091;621.4841681404423;633.1452539072216;639.178655427773;498.5811917988907;522.1984254169907;527.1618127177805;543.5651478816931;568.1789813719219;7379.594122323324;7453.85092298151;7464.477486575887;7554.357879580297;7594.5063570867715;453.6642884265686;536.8550491367387;552.3868505958383;564.1396869773628;635.6376865186799;3732.79469629173;3744;3750.718696097804;3880.2821478026653;4076.2051399977313;93593;96620.51689741506;98191.01808981819;98879.02241955264;99627.50186249023;74221.25778742143;74242.25757742462;74666.6266668674;75188.62405687936;76981.6150919253;70714.2928570718;71149.37529060594;71226;71454.64272678603;72408;7266.636668166676;7319;7353.102089944348;7523.473356864967;7556.168821429528;40610;41045.38431923583;41305.63026806616;43391.78304108459;44406.88982775457;56640.43359566352;64637.67681161564;64786.02820957784;65222.67388663026;65602.34397655963;9873.111419972238;10047.648332308334;10307;10324.709411323585;10483.108935740547;10224.948875255575;11117.666470005914;11209.159313051718;11209.327440353605;11394.487248074023;10169.40680126563;10192.490375481344;10254;10288.897111028793;10514.894851051698;10372.01465860742;10808.513616887101;10844.295120817122;10852.565897364017;10865.728356791144;9832.705018849447;9977;10007.449590272605;10043.296969212377;10046.949765251127;8728.912710873064;8781.736547903573;9010.594523246338;9072.546372681472;9213.907860921305;8378.916210838057;8421;8464.068952415353;8521.744347669579;8713.25937295337 12:11:25 INFO - PROCESS | 2221 | __end_tp_report 12:11:25 INFO - PROCESS | 2221 | __start_cc_report 12:11:25 INFO - PROCESS | 2221 | _x_x_mozilla_cycle_collect,1276 12:11:25 INFO - PROCESS | 2221 | __end_cc_report 12:11:25 INFO - PROCESS | 2221 | __startTimestamp1464289885354__endTimestamp 12:11:25 INFO - PROCESS | 2221 | 12:11:25 INFO - PROCESS | 2221 | ------- Summary: start ------- 12:11:25 INFO - PROCESS | 2221 | Number of tests: 6 12:11:25 INFO - PROCESS | 2221 | 12:11:25 INFO - PROCESS | 2221 | [#0] dojo.html Cycles:165 Average:5760.91 Median:6049.64 stddev:656.78 (10.9%) stddev-sans-first:619.04 12:11:25 INFO - PROCESS | 2221 | Values: 2892.2 3360.2 3409.6 3434.4 3462.8 5652.4 5664.9 5700.0 5788.9 5799.8 5671.6 5690.3 5705.1 5811.8 5821.8 5059.2 5136.7 5182.4 5207.7 5282.8 5278.8 5297.0 5305.3 5305.8 5353.4 5096.3 5162.1 5187.7 5250.7 5256.6 4336.2 5159.0 5179.3 5238.9 5291.5 5121.0 5133.1 5189.2 5195.2 5275.2 5710.9 5728.4 5732.7 5791.7 5817.1 6098.1 6126.2 6167.6 6170.1 6250.7 6230.1 6253.8 6288.7 6290.7 6293.7 5742.8 5883.2 5888.8 5894.0 5911.3 6176.3 6209.6 6217.1 6232.2 6261.3 6157.0 6171.4 6196.2 6205.4 6208.8 6124.4 6213.0 6291.7 6301.9 6303.1 6100.1 6183.2 6184.2 6191.9 6205.2 6110.0 6212.1 6225.1 6269.1 6300.2 4968.3 4970.0 4977.6 4984.5 5017.2 4419.7 4467.2 4507.3 4527.5 4534.1 5660.5 5736.4 5747.8 5749.8 5759.9 6264.0 6266.7 6270.6 6279.6 6287.4 5720.9 5750.4 5757.5 5759.8 5782.4 5451.2 5456.2 5461.0 5469.4 5476.5 6168.6 6217.5 6297.5 6314.7 6327.9 6216.9 6278.2 6294.6 6296.4 6304.0 6286.7 6297.6 6341.3 6347.5 6350.6 6105.8 6150.3 6156.9 6247.9 6283.5 6266.2 6301.6 6307.5 6309.7 6330.6 6205.8 6217.8 6222.7 6229.6 6234.2 6019.0 6080.8 6116.5 6137.6 6175.7 4722.6 6049.6 6089.2 6094.6 6117.2 6180.2 6189.1 6197.0 6200.5 6203.7 5797.8 5927.9 5949.4 6019.8 6022.6 12:11:25 INFO - PROCESS | 2221 | 12:11:25 INFO - PROCESS | 2221 | [#1] ext.html Cycles:180 Average:70967.16 Median:38420.90 stddev:162002.39 (421.7%) stddev-sans-first:162370.49 12:11:25 INFO - PROCESS | 2221 | Values: 514.0 522.1 529.7 534.4 565.6 30321.8 31008.7 42767.8 43220.8 43326.6 39316.4 41145.4 42875.0 44942.3 44951.3 9613.0 9845.6 9847.4 9850.4 9866.4 9587.5 9758.0 9763.8 9777.3 9789.5 9733.2 9795.5 9809.0 9810.1 9818.9 9684.0 9748.8 9776.3 9782.4 9815.0 9670.9 9766.2 9780.1 9784.0 9786.2 41239.8 41646.4 41730.6 41746.0 41758.6 3093.9 3111.6 3260.0 3283.0 3327.7 29207.3 29661.0 30148.2 30231.8 30234.4 2704.8 2715.9 2826.2 2855.6 2859.6 516240.0 518931.7 519732.0 520193.0 520971.0 34072.3 35012.3 58801.7 60420.1 60568.7 30860.0 36038.6 36122.5 36148.6 36201.3 15079.0 15247.3 15262.2 15273.5 15278.4 12757.9 12840.2 12855.0 12872.6 12903.0 6754.7 6821.7 7908.7 7953.6 7967.7 3241.6 3284.4 3288.7 3293.4 3326.8 845.6 959.2 1040.7 1077.0 1081.9 29405.9 29440.6 29560.7 29601.3 30415.4 923.9 1075.3 1075.4 1098.2 1103.0 12653.9 14786.8 14852.9 15073.4 15211.5 709560.0 718899.0 721206.0 721618.0 723500.0 59260.4 61643.0 61651.0 61788.7 61867.4 1293.5 1343.0 1394.8 1438.7 1472.9 28933.3 29422.3 29424.4 30157.4 30199.7 41438.8 50402.7 50661.7 50671.0 50694.0 551669.0 552901.0 553273.0 554249.0 556309.0 23022.7 25195.7 25909.6 26170.7 27079.7 46399.0 46823.8 47051.0 47332.0 48373.0 36992.0 38160.4 38335.8 39004.8 39121.2 37112.4 37552.6 37670.0 38186.6 38617.4 37328.8 37877.6 38320.0 38506.0 38756.0 31118.1 32807.3 33011.5 33248.7 33327.8 24131.9 24541.0 24737.4 24822.1 24852.0 12:11:25 INFO - PROCESS | 2221 | 12:11:25 INFO - PROCESS | 2221 | [#2] jquery.html Cycles:180 Average:36843.24 Median:35821.14 stddev:98924.32 (276.2%) stddev-sans-first:99163.95 12:11:25 INFO - PROCESS | 2221 | Values: 383.5 389.0 389.3 401.4 408.5 19949.9 22026.0 22356.4 22465.4 22490.9 20873.1 21276.3 21506.9 21918.1 21930.0 3855.7 5311.3 5323.1 5353.1 5354.8 3807.1 4027.7 5222.3 7229.1 7655.2 7423.8 7613.9 7644.4 7706.0 7726.8 7491.0 7615.5 7633.1 7635.2 7636.2 4118.7 4617.1 4632.9 4637.2 6808.4 21801.9 22922.0 27896.7 29203.6 29950.6 2363.0 2378.4 2381.4 2412.9 2650.6 15546.9 15947.0 16466.7 16781.9 16878.1 1747.2 1792.5 1822.4 1845.2 1858.8 119592.0 123858.0 132642.0 133770.3 138185.0 16171.5 17838.6 19127.6 19747.4 21717.4 35759.1 35811.0 35831.3 35890.8 35938.1 8123.8 11064.0 11258.3 11361.5 11459.0 9167.5 9173.7 9201.6 9202.0 9305.0 8083.9 9853.0 9873.7 9950.5 9972.9 3679.6 3806.7 3819.9 3869.5 3895.9 811.9 838.7 842.3 903.1 907.0 20227.0 20580.4 20803.6 20882.8 20930.7 906.8 915.4 930.2 938.3 1008.1 8888.0 9340.6 10847.0 11059.4 11076.4 521776.3 593093.0 600371.0 614454.0 615330.0 132184.3 134259.0 134846.3 136787.6 143518.0 998.4 1020.5 1022.5 1061.7 1072.7 16136.4 18459.3 18852.2 18860.5 18927.2 53102.7 54501.5 55214.7 55511.7 56071.7 80409.2 82093.0 83232.0 84614.7 85257.0 7692.8 8041.6 8134.7 8184.2 8446.0 9125.8 11979.6 12846.8 12846.8 12892.8 9312.1 11211.3 11262.6 11565.8 11568.7 4741.7 10434.4 11177.4 11319.4 11416.5 10996.6 11467.8 11704.9 11725.4 11824.2 8136.2 8221.8 8239.9 8243.9 8600.0 10437.8 11221.8 11492.0 11532.7 11566.0 12:11:25 INFO - PROCESS | 2221 | 12:11:25 INFO - PROCESS | 2221 | [#3] mootools.html Cycles:180 Average:5675.85 Median:4831.84 stddev:3201.01 (66.2%) stddev-sans-first:3194.01 12:11:25 INFO - PROCESS | 2221 | Values: 1419.7 1604.4 1605.1 1854.5 1982.5 7811.5 8479.2 8728.2 8732.3 8736.8 8212.2 8545.5 8553.5 8615.7 8653.3 2093.2 2162.2 2166.1 2326.9 2393.4 2188.5 2195.7 2339.9 2403.8 2406.6 2366.2 2376.0 2376.8 2379.6 2385.3 2254.8 2291.0 2295.9 2296.2 2299.9 2115.9 2304.6 2365.1 2366.5 2380.6 8605.2 8681.7 8691.2 8698.0 8700.5 5156.3 5315.4 5318.7 5321.1 5337.2 3462.1 3466.7 3489.5 3555.2 3571.1 977.9 1018.6 1025.8 1026.8 1036.7 11446.2 11698.9 11717.5 11731.6 11757.2 4645.1 4688.2 4801.7 4802.4 4811.2 10181.6 10191.3 10202.7 10206.4 10217.4 4793.9 4924.9 5267.9 5270.6 5283.6 4693.2 4786.8 4900.0 4907.4 4909.5 3788.9 3804.3 3845.9 3882.7 3895.3 1223.9 1254.5 1258.1 1269.9 1272.2 747.4 790.9 811.0 833.3 844.3 5661.3 5756.6 5804.0 5895.5 5984.8 921.2 965.7 966.6 968.8 1012.8 3376.5 3816.2 3881.4 3950.6 3970.4 10306.6 10687.2 10716.4 10716.5 10774.1 10034.9 10524.9 10681.7 10686.5 10707.9 7960.2 8211.3 8256.3 8257.5 8275.2 5743.1 5769.7 5874.5 5878.6 6117.9 9626.6 9961.8 9992.3 10006.6 10008.8 9279.4 9644.9 10053.3 10126.4 10208.8 7580.5 7959.6 8180.6 8224.0 8286.7 8121.1 8201.9 8202.0 8219.8 8249.8 7338.4 7384.9 7457.2 7461.3 7530.0 7193.2 7266.2 7316.3 7368.5 7434.8 6745.6 6936.8 6975.1 7030.7 7042.8 4131.4 4710.7 4822.6 4868.1 5023.7 4841.1 4880.8 4895.8 4929.3 4938.2 12:11:25 INFO - PROCESS | 2221 | 12:11:25 INFO - PROCESS | 2221 | [#4] prototype.html Cycles:180 Average:4415.97 Median:5006.22 stddev:1365.45 (27.3%) stddev-sans-first:1333.37 12:11:25 INFO - PROCESS | 2221 | Values: 270.9 307.2 315.3 323.1 326.9 4436.3 4863.9 5038.4 5278.3 5280.4 5104.7 5174.7 5234.3 5241.4 5265.3 3586.8 3737.0 3762.4 3810.4 3946.4 3930.6 3991.9 3995.1 3999.9 4018.4 3281.7 3476.8 3951.8 3970.7 3996.9 3565.9 3877.0 3890.4 3970.2 3993.8 2956.3 2986.6 3300.8 3360.7 3421.4 4813.5 5009.7 5063.9 5068.7 5091.9 4234.0 4536.8 4543.0 4543.1 4546.5 4613.7 4873.9 5236.6 5323.4 5488.0 3782.5 3793.1 3800.6 3808.3 3836.0 5548.9 5726.4 5726.7 5765.9 5808.9 3636.9 3793.2 3802.8 3817.0 3820.4 3876.2 3880.3 3897.8 3899.5 3903.2 4035.7 4391.4 4504.9 4554.9 4563.6 4724.8 5141.7 5203.7 5395.4 5600.7 1130.7 1309.5 1320.5 1332.6 1341.2 716.9 742.3 772.6 800.9 821.6 4958.6 4967.6 5113.2 5127.9 5146.0 4628.6 5002.7 5545.8 5675.8 5748.3 4803.4 5011.0 5106.7 5126.5 5192.0 2276.6 2451.1 2824.4 2918.7 2969.9 5216.7 5284.6 5326.1 5849.8 5880.9 5379.4 5382.5 5784.4 5805.9 6028.6 4766.6 4784.8 5856.9 5861.2 5892.7 4623.5 5465.4 5642.2 5645.3 5665.9 4458.7 4628.7 5415.0 5566.3 5630.6 5466.3 5493.7 5866.5 5870.9 5886.2 3756.1 3768.5 3777.5 3779.6 3781.9 4983.4 5017.7 5301.6 5660.7 5744.3 4454.3 4658.2 4885.3 5343.8 5497.8 4532.4 5087.9 5441.6 5462.6 5473.3 5352.5 5379.4 5379.8 5384.8 5389.8 5251.6 5398.3 5401.5 5514.8 5515.6 5374.4 5379.2 5380.0 5381.4 5404.4 12:11:25 INFO - PROCESS | 2221 | 12:11:25 INFO - PROCESS | 2221 | [#5] yui.html Cycles:180 Average:17977.63 Median:634.39 stddev:25840.57 (4073.3%) stddev-sans-first:25880.52 12:11:25 INFO - PROCESS | 2221 | Values: 707.3 709.8 728.2 733.8 749.1 13546.5 14965.0 15255.4 15685.7 15697.0 14239.5 14339.2 14397.1 14411.8 14415.1 727.5 746.4 906.4 907.6 912.8 742.3 868.8 901.1 903.8 905.2 964.8 972.0 972.7 978.6 979.9 757.0 936.6 971.9 973.4 975.4 727.1 728.6 730.5 730.9 732.8 14941.1 15010.3 15029.7 15035.3 15051.2 12316.6 12372.5 12492.4 12529.6 12745.4 10937.3 10948.4 11031.3 11083.1 11488.0 4008.7 4025.1 4046.5 4119.9 4153.7 78952.0 79323.0 86775.7 87304.6 87493.6 9411.0 9542.1 9586.2 9730.5 9817.0 21991.1 22026.0 22031.2 22041.2 22073.0 7048.7 7390.1 7403.4 7559.8 7789.3 4052.4 4070.8 4075.3 4082.0 4086.3 3323.2 3457.8 3461.2 3461.9 3462.0 610.2 613.7 621.5 633.1 639.2 498.6 522.2 527.2 543.6 568.2 7379.6 7453.9 7464.5 7554.4 7594.5 453.7 536.9 552.4 564.1 635.6 3732.8 3744.0 3750.7 3880.3 4076.2 93593.0 96620.5 98191.0 98879.0 99627.5 74221.3 74242.3 74666.6 75188.6 76981.6 70714.3 71149.4 71226.0 71454.6 72408.0 7266.6 7319.0 7353.1 7523.5 7556.2 40610.0 41045.4 41305.6 43391.8 44406.9 56640.4 64637.7 64786.0 65222.7 65602.3 9873.1 10047.6 10307.0 10324.7 10483.1 10224.9 11117.7 11209.2 11209.3 11394.5 10169.4 10192.5 10254.0 10288.9 10514.9 10372.0 10808.5 10844.3 10852.6 10865.7 9832.7 9977.0 10007.4 10043.3 10046.9 8728.9 8781.7 9010.6 9072.5 9213.9 8378.9 8421.0 8464.1 8521.7 8713.3 12:11:25 INFO - PROCESS | 2221 | -------- Summary: end -------- 12:11:25 INFO - PROCESS | 2221 | 12:11:25 INFO - TEST-INFO | 2221: exit 0 12:11:25 INFO - TEST-OK | dromaeo_css | took 1115594ms 12:11:25 INFO - TEST-START | kraken 12:11:25 INFO - operating with platform_type : mac_ 12:11:25 INFO - Initialising browser for kraken test... 12:11:25 INFO - TEST-INFO | started process 2226 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmp72ffUc/profile http://localhost:54828/getInfo.html) 12:11:28 INFO - PROCESS | 2226 | __metrics Screen width/height:1600/1200 12:11:28 INFO - PROCESS | 2226 | colorDepth:24 12:11:28 INFO - PROCESS | 2226 | Browser inner width/height: 1024/689 12:11:28 INFO - PROCESS | 2226 | __metrics 12:11:28 INFO - PROCESS | 2226 | 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" 12:11:28 ERROR - PROCESS | 2226 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:11:28 INFO - TEST-INFO | 2226: exit 0 12:11:28 INFO - Browser initialized. 12:11:28 INFO - Running cycle 1/1 for kraken test... 12:11:28 INFO - TEST-INFO | started process 2227 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmp72ffUc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:40 INFO - PROCESS | 2227 | RSS: Main: 241643520 12:11:40 INFO - PROCESS | 2227 | 12:11:43 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54828/tests/kraken/driver.html?testName=audio-beat-detection) 12:11:44 INFO - PROCESS | 2227 | RSS: Main: 289337344 12:11:44 INFO - PROCESS | 2227 | 12:11:47 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54828/tests/kraken/driver.html?testName=audio-dft) 12:11:47 INFO - PROCESS | 2227 | RSS: Main: 298303488 12:11:47 INFO - PROCESS | 2227 | 12:11:51 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54828/tests/kraken/driver.html?testName=audio-fft) 12:11:51 INFO - PROCESS | 2227 | RSS: Main: 389718016 12:11:51 INFO - PROCESS | 2227 | 12:11:53 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54828/tests/kraken/driver.html?testName=audio-oscillator) 12:11:54 INFO - PROCESS | 2227 | RSS: Main: 289476608 12:11:54 INFO - PROCESS | 2227 | 12:11:56 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54828/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:11:57 INFO - PROCESS | 2227 | RSS: Main: 293146624 12:11:57 INFO - PROCESS | 2227 | 12:11:59 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54828/tests/kraken/driver.html?testName=imaging-darkroom) 12:12:00 INFO - PROCESS | 2227 | RSS: Main: 330268672 12:12:00 INFO - PROCESS | 2227 | 12:12:03 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54828/tests/kraken/driver.html?testName=imaging-desaturate) 12:12:04 INFO - PROCESS | 2227 | RSS: Main: 330792960 12:12:04 INFO - PROCESS | 2227 | 12:12:06 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54828/tests/kraken/driver.html?testName=json-parse-financial) 12:12:07 INFO - PROCESS | 2227 | RSS: Main: 331341824 12:12:07 INFO - PROCESS | 2227 | 12:12:09 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54828/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:12:09 INFO - PROCESS | 2227 | RSS: Main: 317644800 12:12:09 INFO - PROCESS | 2227 | 12:12:11 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54828/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:12:12 INFO - PROCESS | 2227 | RSS: Main: 325074944 12:12:12 INFO - PROCESS | 2227 | 12:12:14 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54828/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:12:15 INFO - PROCESS | 2227 | RSS: Main: 325832704 12:12:15 INFO - PROCESS | 2227 | 12:12:18 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54828/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:12:18 INFO - PROCESS | 2227 | RSS: Main: 327671808 12:12:18 INFO - PROCESS | 2227 | 12:12:21 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54828/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:12:22 INFO - PROCESS | 2227 | RSS: Main: 322830336 12:12:22 INFO - PROCESS | 2227 | 12:12:23 INFO - PROCESS | 2227 | Cycle 1(1): loaded http://localhost:54828/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54828/tests/kraken/driver.html?testName=ai-astar) 12:12:24 INFO - PROCESS | 2227 | RSS: Main: 369340416 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | __start_tp_report 12:12:24 INFO - PROCESS | 2227 | _x_x_mozilla_page_load 12:12:24 INFO - PROCESS | 2227 | _x_x_mozilla_page_load_details 12:12:24 INFO - PROCESS | 2227 | |i|pagename|runs| 12:12:24 INFO - PROCESS | 2227 | |0;ai-astar;113;105;113;112;113;112;112;113;111;113 12:12:24 INFO - PROCESS | 2227 | |1;audio-beat-detection;153;129;138;174;141;142;137;112;146;114 12:12:24 INFO - PROCESS | 2227 | |2;audio-dft;187;184;186;182;185;186;181;183;185;185 12:12:24 INFO - PROCESS | 2227 | |3;audio-fft;78;56;60;75;57;72;90;95;95;54 12:12:24 INFO - PROCESS | 2227 | |4;audio-oscillator;86;68;101;70;74;70;95;70;68;100 12:12:24 INFO - PROCESS | 2227 | |5;imaging-gaussian-blur;88;86;89;86;88;84;88;87;88;85 12:12:24 INFO - PROCESS | 2227 | |6;imaging-darkroom;94;95;95;98;149;95;95;95;95;95 12:12:24 INFO - PROCESS | 2227 | |7;imaging-desaturate;124;141;84;86;86;84;87;84;86;86 12:12:24 INFO - PROCESS | 2227 | |8;json-parse-financial;56;54;56;56;57;56;56;71;57;54 12:12:24 INFO - PROCESS | 2227 | |9;json-stringify-tinderbox;65;68;77;78;70;73;68;71;70;70 12:12:24 INFO - PROCESS | 2227 | |10;stanford-crypto-aes;85;86;88;84;86;84;86;86;85;86 12:12:24 INFO - PROCESS | 2227 | |11;stanford-crypto-ccm;139;137;139;151;127;142;146;141;242;138 12:12:24 INFO - PROCESS | 2227 | |12;stanford-crypto-pbkdf2;164;165;163;162;162;161;163;146;189;223 12:12:24 INFO - PROCESS | 2227 | |13;stanford-crypto-sha256-iterative;66;67;68;66;66;66;66;65;67;76 12:12:24 INFO - PROCESS | 2227 | __end_tp_report 12:12:24 INFO - PROCESS | 2227 | __start_cc_report 12:12:24 INFO - PROCESS | 2227 | _x_x_mozilla_cycle_collect,1974 12:12:24 INFO - PROCESS | 2227 | __end_cc_report 12:12:24 INFO - PROCESS | 2227 | __startTimestamp1464289944224__endTimestamp 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | ------- Summary: start ------- 12:12:24 INFO - PROCESS | 2227 | Number of tests: 14 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:2.45 (2.2%) stddev-sans-first:2.55 12:12:24 INFO - PROCESS | 2227 | Values: 113.0 105.0 113.0 112.0 113.0 112.0 112.0 113.0 111.0 113.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:141.50 stddev:18.08 (12.8%) stddev-sans-first:18.41 12:12:24 INFO - PROCESS | 2227 | Values: 153.0 129.0 138.0 174.0 141.0 142.0 137.0 112.0 146.0 114.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.90 (1.0%) stddev-sans-first:1.76 12:12:24 INFO - PROCESS | 2227 | Values: 187.0 184.0 186.0 182.0 185.0 186.0 181.0 183.0 185.0 185.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#3] audio-fft Cycles:10 Average:73.20 Median:76.50 stddev:16.20 (21.2%) stddev-sans-first:17.09 12:12:24 INFO - PROCESS | 2227 | Values: 78.0 56.0 60.0 75.0 57.0 72.0 90.0 95.0 95.0 54.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:70.00 stddev:13.85 (19.8%) stddev-sans-first:14.53 12:12:24 INFO - PROCESS | 2227 | Values: 86.0 68.0 101.0 70.0 74.0 70.0 95.0 70.0 68.0 100.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:1.60 (1.8%) stddev-sans-first:1.64 12:12:24 INFO - PROCESS | 2227 | Values: 88.0 86.0 89.0 86.0 88.0 84.0 88.0 87.0 88.0 85.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:95.00 stddev:17.04 (17.9%) stddev-sans-first:17.90 12:12:24 INFO - PROCESS | 2227 | Values: 94.0 95.0 95.0 98.0 149.0 95.0 95.0 95.0 95.0 95.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:86.00 stddev:20.30 (23.6%) stddev-sans-first:18.57 12:12:24 INFO - PROCESS | 2227 | Values: 124.0 141.0 84.0 86.0 86.0 84.0 87.0 84.0 86.0 86.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:56.00 stddev:4.92 (8.8%) stddev-sans-first:5.20 12:12:24 INFO - PROCESS | 2227 | Values: 56.0 54.0 56.0 56.0 57.0 56.0 56.0 71.0 57.0 54.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:70.50 stddev:4.03 (5.7%) stddev-sans-first:3.64 12:12:24 INFO - PROCESS | 2227 | Values: 65.0 68.0 77.0 78.0 70.0 73.0 68.0 71.0 70.0 70.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.00 stddev:1.17 (1.4%) stddev-sans-first:1.22 12:12:24 INFO - PROCESS | 2227 | Values: 85.0 86.0 88.0 84.0 86.0 84.0 86.0 86.0 85.0 86.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:141.50 stddev:32.85 (23.2%) stddev-sans-first:34.59 12:12:24 INFO - PROCESS | 2227 | Values: 139.0 137.0 139.0 151.0 127.0 142.0 146.0 141.0 242.0 138.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:163.50 stddev:21.38 (13.1%) stddev-sans-first:22.57 12:12:24 INFO - PROCESS | 2227 | Values: 164.0 165.0 163.0 162.0 162.0 161.0 163.0 146.0 189.0 223.0 12:12:24 INFO - PROCESS | 2227 | 12:12:24 INFO - PROCESS | 2227 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:66.50 stddev:3.16 (4.8%) stddev-sans-first:3.32 12:12:24 INFO - PROCESS | 2227 | Values: 66.0 67.0 68.0 66.0 66.0 66.0 66.0 65.0 67.0 76.0 12:12:24 INFO - PROCESS | 2227 | -------- Summary: end -------- 12:12:24 INFO - PROCESS | 2227 | 12:12:25 INFO - TEST-INFO | 2227: exit 0 12:12:25 INFO - TEST-OK | kraken | took 59219ms 12:12:25 INFO - SUITE-END | took 1174s 12:12:25 INFO - Completed test suite (00:19:35) 12:12:25 INFO - javascript benchmark 12:12:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2892.2335581071006, 3360.1935535471484, 3409.5567576215094, 3434.433318502447, 3462.792232466052, 5652.406497317779, 5664.915026274599, 5700.002499562581, 5788.855278618036, 5799.768009279624, 5671.574631902603, 5690.2887139107615, 5705.058665320227, 5811.767529298823, 5821.767129314822, 5059.190529515278, 5136.691798492103, 5182.352205974254, 5207.687538747668, 5282.788688452457, 5278.762455689484, 5297.046531624306, 5305.336832895888, 5305.814296499623, 5353.384360798519, 5096.261042148886, 5162.1224391853475, 5187.662801917863, 5250.68495890244, 5256.553192978601, 4336.241157797404, 5158.97420512895, 5179.326687530634, 5238.8690282742855, 5291.523762861322, 5121.0, 5133.076046311662, 5189.2475591039465, 5195.246689230078, 5275.235090911796, 5710.885782284373, 5728.398518155617, 5732.656040637575, 5791.73937172824, 5817.0692689169955, 6098.140067279478, 6126.151269885583, 6167.599106058092, 6170.074488826712, 6250.749970001195, 6230.096635987802, 6253.781117660906, 6288.717007734663, 6290.748370065192, 6293.685315734195, 5742.799002034909, 5883.23517942669, 5888.764449421975, 5893.96855550277, 5911.29064512257, 6176.289726681489, 6209.59637623553, 6217.067439884054, 6232.189815323979, 6261.342559031327, 6157.0, 6171.413715697002, 6196.225471816023, 6205.441510264098, 6208.844778880564, 6124.350421260821, 6212.968935155295, 6291.748330066746, 6301.936980630135, 6303.086052522359, 6100.066499260575, 6183.165272688129, 6184.165137706352, 6191.938080619136, 6205.22434695663, 6109.951287404261, 6212.1303017577575, 6225.128482012522, 6269.059641053878, 6300.243970723543, 4968.254761785761, 4969.950300497021, 4977.601791856643, 4984.476629953875, 5017.2223305387, 4419.712718673276, 4467.15124126415, 4507.301368287921, 4527.524609915911, 4534.115847409789, 5660.462256085665, 5736.426357364314, 5747.798827041117, 5749.798757043483, 5759.913601295984, 6264.0, 6266.686665666698, 6270.592411493239, 6279.560430769893, 6287.434130928238, 5720.914186287209, 5750.396208398058, 5757.510611598061, 5759.769609215585, 5782.421757824184, 5451.209574611738, 5456.154296084113, 5461.044317244467, 5469.425710300361, 5476.479734425223, 6168.629882207082, 6217.502599792097, 6297.4647154992335, 6314.652694101868, 6327.873442531123, 6216.937830621636, 6278.246610406781, 6294.559380843297, 6296.37036296385, 6303.9684801575695, 6286.748530058747, 6297.590656607489, 6341.270753863365, 6347.5239357047485, 6350.555461117678, 6105.8168254952425, 6150.261968563801, 6156.922538555825, 6247.875042499216, 6283.528735344776, 6266.248050234001, 6301.590396624205, 6307.495400367868, 6309.652969086745, 6330.556861019684, 6205.751769929333, 6217.813465596039, 6222.657753823402, 6229.56393052482, 6234.220722409699, 6019.006863867413, 6080.7567697291615, 6116.457510225689, 6137.60105593135, 6175.660338681236, 4722.622190224705, 6049.637021778707, 6089.208402907594, 6094.573379863367, 6117.235345581802, 6180.227471566054, 6189.102580125947, 6197.03945888388, 6200.503959683124, 6203.658798766111, 5797.797077102281, 5927.911081333697, 5949.434803693643, 6019.759209631741, 6022.6386416813375], "value": 5717.8459400535285, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.9948600513995, 522.091560684409, 529.7060131626948, 534.3587694766277, 565.5560385097698, 30321.84839075804, 31008.68991310086, 42767.786161069154, 43220.78389608047, 43326.56673433265, 39316.41025384622, 41145.382819257735, 42875.0, 44942.32586511205, 44951.32573011408, 9613.038696130383, 9845.60617575296, 9847.359921605108, 9850.40897546146, 9866.358686685378, 9587.472689002103, 9758.0, 9763.755906102333, 9777.266704997119, 9789.510524473784, 9733.2213422926, 9795.461249631304, 9808.950955245215, 9810.117089461946, 9818.852717209247, 9683.951580242088, 9748.756281093029, 9776.266779991494, 9782.413055216639, 9815.0, 9670.854937175947, 9766.169875560587, 9780.0708932652, 9783.951080244553, 9786.217102631843, 41239.793801030806, 41646.37530437046, 41730.582694172976, 41746.0, 41758.58241417578, 3093.8607762650736, 3111.6110486189227, 3260.02199340197, 3283.015095471349, 3327.667233276677, 29207.269818254503, 29660.957707095247, 30148.24629384261, 30231.848840755876, 30234.395312093635, 2704.7700945419665, 2715.945681086387, 2826.1662809471254, 2855.6430446194227, 2859.642544681915, 516240.0, 518931.67478276533, 519732.0, 520193.0, 520971.0, 34072.31855362929, 35012.29975400529, 58801.705991470626, 60420.09369859456, 60568.69715651393, 30860.0, 36038.639613603526, 36122.45816312757, 36148.638513614525, 36201.27597448036, 15079.019863708823, 15247.313870875838, 15262.236888155549, 15273.472652734637, 15278.388864445298, 12757.872421275853, 12840.165389249856, 12855.035872309614, 12872.613821585368, 12903.032272579594, 6754.662266886636, 6821.69302381394, 7908.723194688158, 7953.5625540594665, 7967.721129760431, 3241.6272128705027, 3284.4088064148204, 3288.720458761032, 3293.357795229955, 3326.8170250635962, 845.5856630251133, 959.2182371367313, 1040.7190058684114, 1077.0, 1081.929674571166, 29405.852970735436, 29440.5653738464, 29560.704392956224, 29601.25996850053, 30415.39169216603, 923.930705197113, 1075.2849355178719, 1075.408525311076, 1098.181854518373, 1103.0403548912336, 12653.87346126527, 14786.778198327034, 14852.886033547527, 15073.397064117313, 15211.543653690407, 709560.0, 718899.0, 721206.0, 721618.0, 723500.0, 59260.40739592635, 61643.0, 61651.0, 61788.69105654443, 61867.38132618706, 1293.5213970830664, 1342.9731405372033, 1394.776835706282, 1438.7122575484946, 1472.889533285008, 28933.276668083465, 29422.264443389085, 29424.411511769216, 30157.396852062397, 30199.69800301969, 41438.79280603577, 50402.747986259834, 50661.746691267785, 50671.0, 50694.0, 551669.0, 552901.0, 553273.0, 554249.0, 556309.0, 23022.654660180448, 25195.748042520074, 25909.6113558293, 26170.738292617592, 27079.72920270772, 46399.0, 46823.76588117037, 47051.0, 47332.0, 48373.0, 36992.0, 38160.427593586675, 38335.80832095822, 39004.80497597494, 39121.21757564776, 37112.44331334978, 37552.62447375491, 37670.0, 38186.61813381831, 38617.42073868838, 37328.813355933045, 37877.621223787406, 38320.041998949135, 38506.0, 38756.0, 31118.066458006295, 32807.34385312328, 33011.504827427125, 33248.66751332456, 33327.83336083304, 24131.914063866818, 24541.0, 24737.381565461008, 24822.131225407025, 24852.0], "value": 18541.929988931715, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.5091083413231, 388.9611038896111, 389.28371795895555, 401.43999121225875, 408.4853085112757, 19949.90275534845, 22026.0, 22356.441088972784, 22465.438364040885, 22490.875456227204, 20873.060712267947, 21276.255331063418, 21506.89246553769, 21918.123275068978, 21930.0, 3855.6722678572355, 5311.256424100629, 5323.068463018975, 5353.08997470429, 5354.839354819362, 3807.1243613968804, 4027.697922655798, 5222.268882356473, 7229.096362954604, 7655.234476552356, 7423.777286681381, 7613.885791713129, 7644.35023023041, 7706.0, 7726.8454630907345, 7490.962545187239, 7615.5430674159725, 7633.08402991639, 7635.236476352348, 7636.236376362347, 4118.711690181688, 4617.053504031699, 4632.930506042412, 4637.234856248715, 6808.387245147911, 21801.890990545104, 22921.96851141686, 27896.721032789614, 29203.56194657082, 29950.55074173889, 2362.988185059081, 2378.393509655044, 2381.4403615150427, 2412.9396765080837, 2650.642163307948, 15546.922265388714, 15947.0, 16466.67066658672, 16781.916090419592, 16878.071706056162, 1747.2399506214838, 1792.471220989798, 1822.371281907753, 1845.1696736468643, 1858.8417142429864, 119592.0, 123858.0, 132642.0, 133770.33114834363, 138185.0, 16171.514854554382, 17838.648405478303, 19127.56543259261, 19747.407577772403, 21717.43933773925, 35759.106022349646, 35811.0, 35831.283374332364, 35890.820545897106, 35938.10154746152, 8123.75628731139, 11064.0, 11258.324500529994, 11361.488733007034, 11459.025982791389, 9167.495787731605, 9173.724788256362, 9201.585928633094, 9202.0, 9304.97645259021, 8083.919160808434, 9853.0, 9873.703788886345, 9950.502474876228, 9972.900270997197, 3679.5952445231005, 3806.6764325032127, 3819.923601527954, 3869.5163104611925, 3895.902602434962, 811.9066307374609, 838.6813011055759, 842.2882664148784, 903.13299232736, 907.0, 20226.98865056741, 20580.382588522367, 20803.583928321652, 20882.79117208839, 20930.686039709417, 906.7869050773063, 915.3775432705824, 930.237205491504, 938.2681508423441, 1008.1330056151704, 8888.0, 9340.626374944926, 10847.0, 11059.447027648584, 11076.446177691083, 521776.30230337696, 593093.0, 600371.0, 614454.0, 615330.0, 132184.33907830398, 134259.0, 134846.32576837248, 136787.63212367948, 143518.0, 998.381003777648, 1020.4999550220339, 1022.544967489475, 1061.6549621373085, 1072.7371793910386, 16136.354545818036, 18459.261629534667, 18852.151653175915, 18860.528486787392, 18927.242910283436, 53102.734486327325, 54501.45498545122, 55214.72392638011, 55511.72244138754, 56071.719641401534, 80409.19590804251, 82093.0, 83232.0, 84614.7307790396, 85257.0, 7692.8461430772195, 8041.557714325768, 8134.674613015413, 8184.2224988625985, 8445.957770211355, 9125.817483650422, 11979.580714674945, 12846.807297890353, 12846.807297890726, 12892.806607901077, 9312.115349041831, 11211.327320360804, 11262.605808796652, 11565.768684626428, 11568.652940411801, 4741.739204343794, 10434.426106564211, 11177.441127943408, 11319.434028298718, 11416.543338266376, 10996.560137594406, 11467.770644586895, 11704.88295117072, 11725.41372931333, 11824.172307938361, 8136.1863813618165, 8221.753347399588, 8239.917600823916, 8243.917560824315, 8600.010998734975, 10437.843432348369, 11221.775564488828, 11492.0, 11532.711682208012, 11566.0], "value": 10714.585445220357, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.701862608852, 1604.4224079331443, 1605.1412494315543, 1854.4622059602716, 1982.454824923146, 7811.531308121516, 8479.236868681817, 8728.170823771747, 8732.30141588673, 8736.781580460483, 8212.178782121784, 8545.529995850226, 8553.529555874424, 8615.698450554217, 8653.30773538117, 2093.2150443583655, 2162.167565487283, 2166.0577648722856, 2326.906923723049, 2393.4255778613096, 2188.485705859122, 2195.692603035576, 2339.894704738282, 2403.831731778776, 2406.578848701479, 2366.2073205476186, 2376.0, 2376.8098552115875, 2379.5597814404305, 2385.2844146755992, 2254.7745225477483, 2291.049214575957, 2295.885205739723, 2296.196331284054, 2299.9425014374606, 2115.936521904345, 2304.6082166031647, 2365.113082594027, 2366.4557151854997, 2380.571497130516, 8605.225529702357, 8681.739547813575, 8691.21779039883, 8698.0, 8700.521471319074, 5156.303898973628, 5315.388730295988, 5318.654287471302, 5321.148616221425, 5337.172738225581, 3462.0998540379433, 3466.6706662866986, 3489.5114683944266, 3555.2178520725397, 3571.08937221007, 977.8826540815147, 1018.6180182431589, 1025.753819083422, 1026.8151732688113, 1036.6889933020138, 11446.198766086378, 11698.947094761512, 11717.472713727902, 11731.589394371242, 11757.235779674294, 4645.094206629742, 4688.179568575554, 4801.663883528119, 4802.447718512346, 4811.230203167476, 10181.592736290466, 10191.286609937233, 10202.693919182437, 10206.387616743019, 10217.386956782468, 4793.880152996133, 4924.901501969941, 5267.868303292448, 5270.631055826132, 5283.550898173621, 4693.20215563355, 4786.7845946931775, 4900.0, 4907.435644900811, 4909.533594308535, 3788.9242215156096, 3804.2771873343977, 3845.8653947112275, 3882.7087968402484, 3895.25990061893, 1223.9449224784905, 1254.5044707340548, 1258.1318889965894, 1269.8793614606598, 1272.166730791333, 747.3722073458321, 790.9129995700468, 810.9716159934374, 833.295864994079, 844.3203221406703, 5661.2923384576925, 5756.5682573807135, 5803.970980145073, 5895.498882594941, 5984.820455386345, 921.2445794448488, 965.6716716316346, 966.5988614724951, 968.7868668892833, 1012.8379459286626, 3376.4935259710746, 3816.236752649426, 3881.3983832506005, 3950.6246906543834, 3970.404439334065, 10306.639267625596, 10687.198460115522, 10716.410597417062, 10716.464176791129, 10774.084202842847, 10034.899651003543, 10524.947505249567, 10681.679549613524, 10686.519106640064, 10707.892921070845, 7960.2039796019935, 8211.3430925527, 8256.25693687559, 8257.463264887763, 8275.213854683796, 5743.1385292206505, 5769.653820770767, 5874.471297583238, 5878.647281163144, 6117.908231376622, 9626.566804493674, 9961.800763984824, 9992.300538962494, 10006.649767258112, 10008.84986725185, 9279.350445468752, 9644.9035509644, 10053.296269261082, 10126.443045632266, 10208.846867297145, 7580.507267027626, 7959.641816118405, 8180.631871565913, 8224.013118425828, 8286.70996515119, 8121.106678265268, 8201.876971845546, 8201.95899020501, 8219.753407397788, 8249.83500330002, 7338.412926965723, 7384.926150738639, 7457.179710231979, 7461.291177338144, 7529.962350188214, 7193.2087470379265, 7266.2007179211305, 7316.304951029643, 7368.484206105735, 7434.814129646586, 6745.561538499982, 6936.79189624312, 6975.058367120577, 7030.683619237045, 7042.7887163385185, 4131.380292956081, 4710.69380490267, 4822.614190864792, 4868.148074087091, 5023.6985780853265, 4841.05599408112, 4880.755962201947, 4895.849475373356, 4929.309896614405, 4938.234573641092], "value": 4541.789530411471, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.89976708617814, 307.15379130495484, 315.3094722557598, 323.09694404140424, 326.9002954098999, 4436.312371582402, 4863.878403039926, 5038.395392552894, 5278.334441107236, 5280.392754833195, 5104.672785075879, 5174.741262936857, 5234.345706786652, 5241.371035475749, 5265.262863199156, 3586.7668601540945, 3737.0103916866533, 3762.398016317383, 3810.3522401191726, 3946.4080387941754, 3930.6242815014803, 3991.8802435926823, 3995.1410446753944, 3999.9200015999663, 4018.4374187613757, 3281.7210537104256, 3476.774009689375, 3951.7628942263414, 3970.6698256084246, 3996.900077498057, 3565.8573657053685, 3877.034068937265, 3890.3580909149814, 3970.2258059678375, 3993.8402463901407, 2956.314900506712, 2986.5669477925794, 3300.8019518828946, 3360.6807353301397, 3421.426143973775, 4813.494583068762, 5009.7495125243595, 5063.949360506384, 5068.670536415121, 5091.949080509185, 4233.957660423387, 4536.7504787236685, 4543.023784653653, 4543.091901401419, 4546.4544254689645, 4613.677042607019, 4873.902521949577, 5236.554892834114, 5323.387810401815, 5488.0, 3782.4893639358884, 3793.146542028049, 3800.5819359870393, 3808.333541630231, 3836.040989752562, 5548.889022219573, 5726.3700992890745, 5726.742296596622, 5765.913511297334, 5808.912866307009, 3636.9272614547563, 3793.1655035892613, 3802.7908465034134, 3817.0457385653585, 3820.3505404081375, 3876.2441323942126, 3880.301545721797, 3897.785621790829, 3899.4930659014713, 3903.2388684206308, 4035.6973227008093, 4391.429114215131, 4504.864854054384, 4554.863354099382, 4563.634909207255, 4724.834630787906, 5141.665791723526, 5203.725087353622, 5395.3525576931015, 5600.663960162322, 1130.722283819279, 1309.5351150341683, 1320.4748515464653, 1332.5868980615846, 1341.161773891318, 716.9426445884317, 742.3244847189032, 772.5673622771266, 800.8678568036325, 821.5892053973014, 4958.628102892226, 4967.584238492084, 5113.233015047773, 5127.9230811537855, 5145.9742701287005, 4628.560286772751, 5002.699838009731, 5545.833624991256, 5675.829725108253, 5748.310202775694, 4803.3995750531185, 5011.0, 5106.719130447861, 5126.538611524906, 5191.97404012985, 2276.635738281867, 2451.105346548497, 2824.3645179834375, 2918.6720042380666, 2969.8663560139844, 5216.686998780086, 5284.630075894727, 5326.121190003683, 5849.766009359663, 5880.911786323209, 5379.354477462652, 5382.542483888835, 5784.421557844266, 5805.912911306334, 6028.613418913675, 4766.642501812378, 4784.827717209306, 5856.912146317809, 5861.208736820475, 5892.705364731832, 4623.514530974199, 5465.371482279589, 5642.238297829905, 5645.266115404971, 5665.943340566541, 4458.665600079942, 4628.652851036119, 5415.0, 5566.276384070045, 5630.577706671934, 5466.298982874804, 5493.725313734377, 5866.530677545701, 5870.853228669317, 5886.23478947734, 3756.0985363513105, 3768.4724138620068, 3777.5089238399933, 3779.603141670085, 3781.905452363713, 4983.377077865267, 5017.749112544285, 5301.602379821452, 5660.688662123458, 5744.253247077853, 4454.331850222363, 4658.228354129287, 4885.267209918541, 5343.759530821045, 5497.752601132878, 4532.3654688342995, 5087.898242035212, 5441.619086663895, 5462.552423607826, 5473.31583552056, 5352.5450336721815, 5379.4351593082165, 5379.838604841861, 5384.784608615611, 5389.787297857951, 5251.553617942518, 5398.272552783072, 5401.514583489572, 5514.80698175578, 5515.61390702647, 5374.381946076095, 5379.193121031876, 5380.009396523312, 5381.381141168659, 5404.378496472956], "value": 3974.4318284180954, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.2538471912134, 709.8296408861873, 728.2244409703666, 733.8092096055027, 749.1197842535026, 13546.458141674333, 14965.0, 15255.389784408624, 15685.686286274267, 15697.0, 14239.501617443391, 14339.21134337611, 14397.064190827588, 14411.78382324266, 14415.06302090366, 727.4725823777745, 746.3767753925465, 906.4017748286133, 907.6142639378271, 912.8402529557334, 742.3096520236178, 868.8349213649386, 901.0538934119181, 903.7650210945136, 905.226031742857, 964.7877466957293, 972.027972027972, 972.7227740094074, 978.6330126202674, 979.9265055120894, 756.9697212111508, 936.550455781222, 971.917387022104, 973.3721749471622, 975.3855071305068, 727.1456039154026, 728.5774250934445, 730.4521608793405, 730.8720973829612, 732.8351120997786, 14941.103533788008, 15010.324535395934, 15029.699406011929, 15035.323410446555, 15051.247437628075, 12316.568920087844, 12372.505099795997, 12492.437840297118, 12529.624111276677, 12745.426455809511, 10937.2890762101, 10948.3978381189, 11031.282966607145, 11083.113350931906, 11487.970425432492, 4008.67930565557, 4025.0742329264435, 4046.4739583854207, 4119.876403707894, 4153.688473364479, 78952.0, 79323.0, 86775.69836452522, 87304.56347718285, 87493.56253218693, 9411.0, 9542.141207291239, 9586.185174260127, 9730.464824434725, 9817.01829817027, 21991.120355185612, 22026.0, 22031.228906988177, 22041.228557000428, 22073.0, 7048.682809273595, 7390.113186417557, 7403.444741644398, 7559.848803024019, 7789.260020298063, 4052.392141178788, 4070.816813243411, 4075.286824805647, 4082.0, 4086.325756250245, 3323.21904352477, 3457.8098204598987, 3461.1693193633346, 3461.913452163716, 3461.9653803462147, 610.1914962674474, 613.6808859393091, 621.4841681404423, 633.1452539072216, 639.178655427773, 498.5811917988907, 522.1984254169907, 527.1618127177805, 543.5651478816931, 568.1789813719219, 7379.594122323324, 7453.85092298151, 7464.477486575887, 7554.357879580297, 7594.5063570867715, 453.6642884265686, 536.8550491367387, 552.3868505958383, 564.1396869773628, 635.6376865186799, 3732.79469629173, 3744.0, 3750.718696097804, 3880.2821478026653, 4076.2051399977313, 93593.0, 96620.51689741506, 98191.01808981819, 98879.02241955264, 99627.50186249023, 74221.25778742143, 74242.25757742462, 74666.6266668674, 75188.62405687936, 76981.6150919253, 70714.2928570718, 71149.37529060594, 71226.0, 71454.64272678603, 72408.0, 7266.636668166676, 7319.0, 7353.102089944348, 7523.473356864967, 7556.168821429528, 40610.0, 41045.38431923583, 41305.63026806616, 43391.78304108459, 44406.88982775457, 56640.43359566352, 64637.67681161564, 64786.02820957784, 65222.67388663026, 65602.34397655963, 9873.111419972238, 10047.648332308334, 10307.0, 10324.709411323585, 10483.108935740547, 10224.948875255575, 11117.666470005914, 11209.159313051718, 11209.327440353605, 11394.487248074023, 10169.40680126563, 10192.490375481344, 10254.0, 10288.897111028793, 10514.894851051698, 10372.01465860742, 10808.513616887101, 10844.295120817122, 10852.565897364017, 10865.728356791144, 9832.705018849447, 9977.0, 10007.449590272605, 10043.296969212377, 10046.949765251127, 8728.912710873064, 8781.736547903573, 9010.594523246338, 9072.546372681472, 9213.907860921305, 8378.916210838057, 8421.0, 8464.068952415353, 8521.744347669579, 8713.25937295337], "value": 6717.907558284149, "unit": "score"}], "name": "dromaeo_css", "value": 7186.613383575648, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 113.0, 112.0, 113.0, 112.0, 112.0, 113.0, 111.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 129.0, 138.0, 174.0, 141.0, 142.0, 137.0, 112.0, 146.0, 114.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 186.0, 182.0, 185.0, 186.0, 181.0, 183.0, 185.0, 185.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 56.0, 60.0, 75.0, 57.0, 72.0, 90.0, 95.0, 95.0, 54.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 101.0, 70.0, 74.0, 70.0, 95.0, 70.0, 68.0, 100.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 84.0, 88.0, 87.0, 88.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 98.0, 149.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 141.0, 84.0, 86.0, 86.0, 84.0, 87.0, 84.0, 86.0, 86.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 56.0, 57.0, 56.0, 56.0, 71.0, 57.0, 54.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 77.0, 78.0, 70.0, 73.0, 68.0, 71.0, 70.0, 70.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 88.0, 84.0, 86.0, 84.0, 86.0, 86.0, 85.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 139.0, 151.0, 127.0, 142.0, 146.0, 141.0, 242.0, 138.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 163.0, 162.0, 162.0, 161.0, 163.0, 146.0, 189.0, 223.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0, 76.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1471.6, "alertThreshold": 2.0}]} 12:12:25 INFO - Return code: 0 12:12:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:12:25 ERROR - # TBPL SUCCESS # 12:12:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:12:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:12:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:12:25 INFO - 2016-05-26 12:12:25.539 screenresolution[2231:21548] starting screenresolution argv=screenresolution get 12:12:25 INFO - 2016-05-26 12:12:25.541 screenresolution[2231:21548] Display 0: 1600x1200x32@60 12:12:25 INFO - 2016-05-26 12:12:25.547 screenresolution[2232:21551] starting screenresolution argv=screenresolution list 12:12:25 INFO - Available Modes on Display 0 12:12:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:12:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:12:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:12:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:12:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:12:25 INFO - Intel Iris: 12:12:25 INFO - Chipset Model: Intel Iris 12:12:25 INFO - Type: GPU 12:12:25 INFO - Bus: Built-In 12:12:25 INFO - VRAM (Dynamic, Max): 1536 MB 12:12:25 INFO - Vendor: Intel (0x8086) 12:12:25 INFO - Device ID: 0x0a2e 12:12:25 INFO - Revision ID: 0x0009 12:12:25 INFO - Displays: 12:12:25 INFO - Display: 12:12:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:12:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:12:25 INFO - Display Serial Number: 1600x1200 60 12:12:25 INFO - Main Display: Yes 12:12:25 INFO - Mirror: Off 12:12:25 INFO - Online: Yes 12:12:25 INFO - Rotation: Supported 12:12:25 INFO - Return code: 0 12:12:25 INFO - Running post-action listener: _resource_record_post_action 12:12:25 INFO - ##### 12:12:25 INFO - ##### Finished run-tests step (success) 12:12:25 INFO - ##### 12:12:25 INFO - Running post-run listener: _resource_record_post_run 12:12:25 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 421888; Write bytes: 694264832; Read time: 4; Write time: 1906 12:12:25 INFO - TinderboxPrint: CPU usage
53.8% 12:12:25 INFO - TinderboxPrint: I/O read bytes / time
421,888 / 4 12:12:25 INFO - TinderboxPrint: I/O write bytes / time
694,264,832 / 1,906 12:12:25 INFO - TinderboxPrint: CPU idle
2,235.9 (46.2%) 12:12:25 INFO - TinderboxPrint: CPU system
969.3 (20.0%) 12:12:25 INFO - TinderboxPrint: CPU user
1,636.4 (33.8%) 12:12:25 INFO - TinderboxPrint: Swap in / out
396,881,920 / 0 12:12:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382560768; Write bytes: 376940032; Read time: 28368; Write time: 761 12:12:25 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 421888; Write bytes: 294469120; Read time: 4; Write time: 1086 12:12:26 INFO - Running post-run listener: _upload_blobber_files 12:12:26 INFO - Blob upload gear active. 12:12:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:12:26 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 12:12:26 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'] 12:12:26 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 12:12:26 INFO - (blobuploader) - INFO - Open directory for files ... 12:12:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:12:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:12:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:27 INFO - (blobuploader) - INFO - Done attempting. 12:12:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:12:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:12:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:29 INFO - (blobuploader) - INFO - Done attempting. 12:12:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:12:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:12:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:31 INFO - (blobuploader) - INFO - Done attempting. 12:12:31 INFO - (blobuploader) - INFO - Iteration through files over. 12:12:31 INFO - Return code: 0 12:12:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:12:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:12:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d474bb8a2b5d79d99c40567b0a73deec2d93ad78b3579bca5f2e099fdcc5b28470b899e2faca0a39f31adf41794bf8d372a61038a0f8586da54ae849e600cf0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/95313958115c51b7b048613f6bc77696d56a09278659f48334bc6efaa0d5424ee580d2d25032e793a2b8eed0cddc4bc9e79930c70c4b19c46114a3184c7c04d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69688bc534038801e005cb59a165a3766f1fce966055960398616cd064a843aa2013c4c52bb3fbd52ed9ce305d49baab93141c2094605c8b35c8e08d7d0aa909"} 12:12:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:12:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:12:31 INFO - Contents: 12:12:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6d474bb8a2b5d79d99c40567b0a73deec2d93ad78b3579bca5f2e099fdcc5b28470b899e2faca0a39f31adf41794bf8d372a61038a0f8586da54ae849e600cf0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/95313958115c51b7b048613f6bc77696d56a09278659f48334bc6efaa0d5424ee580d2d25032e793a2b8eed0cddc4bc9e79930c70c4b19c46114a3184c7c04d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69688bc534038801e005cb59a165a3766f1fce966055960398616cd064a843aa2013c4c52bb3fbd52ed9ce305d49baab93141c2094605c8b35c8e08d7d0aa909"} 12:12:31 INFO - Running post-run listener: copy_logs_to_upload_dir 12:12:31 INFO - Copying logs to upload dir... 12:12:31 INFO - mkdir: /builds/slave/test/build/upload/logs 12:12:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.834958 ========= master_lag: 1.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-26 12:12:33.678242) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 12:12:33.682873) ========= 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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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/6d474bb8a2b5d79d99c40567b0a73deec2d93ad78b3579bca5f2e099fdcc5b28470b899e2faca0a39f31adf41794bf8d372a61038a0f8586da54ae849e600cf0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/95313958115c51b7b048613f6bc77696d56a09278659f48334bc6efaa0d5424ee580d2d25032e793a2b8eed0cddc4bc9e79930c70c4b19c46114a3184c7c04d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69688bc534038801e005cb59a165a3766f1fce966055960398616cd064a843aa2013c4c52bb3fbd52ed9ce305d49baab93141c2094605c8b35c8e08d7d0aa909"} build_url:https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011093 build_url: 'https://queue.taskcluster.net/v1/task/Znhe8c4hR8yE94UEM1UwDg/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/6d474bb8a2b5d79d99c40567b0a73deec2d93ad78b3579bca5f2e099fdcc5b28470b899e2faca0a39f31adf41794bf8d372a61038a0f8586da54ae849e600cf0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/95313958115c51b7b048613f6bc77696d56a09278659f48334bc6efaa0d5424ee580d2d25032e793a2b8eed0cddc4bc9e79930c70c4b19c46114a3184c7c04d1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69688bc534038801e005cb59a165a3766f1fce966055960398616cd064a843aa2013c4c52bb3fbd52ed9ce305d49baab93141c2094605c8b35c8e08d7d0aa909"}' ========= master_lag: 0.54 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 12:12:34.234813) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:12:34.235351) ========= 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.4DgAZ1ZrwD/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.0WLQABfCii/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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.005089 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:12:34.259703) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 12:12:34.260047) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 12:12:34.260460) ========= ========= Total master_lag: 1.90 =========