builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0035 starttime: 1463790248.47 results: success (0) buildid: 20160520162836 builduid: 12f81a960b0b4f8b928eb1eab627d70f revision: 9804d9410b32b53e7fc6a497a6c3d731a78495a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:08.474836) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:08.475256) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:08.475539) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:08.498215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:08.499075) ========= 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.wILTwZ9RKa/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.5H2QghSkHo/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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-20 17:24:08-- 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% 19.7M=0.001s 2016-05-20 17:24:08 (19.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.157292 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:08.691289) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:08.691648) ========= 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.wILTwZ9RKa/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.5H2QghSkHo/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.019955 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:08.741889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 17:24:08.742261) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9804d9410b32b53e7fc6a497a6c3d731a78495a6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 9804d9410b32b53e7fc6a497a6c3d731a78495a6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wILTwZ9RKa/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.5H2QghSkHo/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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-20 17:24:08,520 truncating revision to first 12 chars 2016-05-20 17:24:08,521 Setting DEBUG logging. 2016-05-20 17:24:08,521 attempt 1/10 2016-05-20 17:24:08,521 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9804d9410b32?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 17:24:10,255 unpacking tar archive at: fx-team-9804d9410b32/testing/mozharness/ program finished with exit code 0 elapsedTime=1.974556 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 17:24:10.736345) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:10.736759) ========= script_repo_revision: 9804d9410b32b53e7fc6a497a6c3d731a78495a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:10.737257) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:10.737542) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 17:24:10.756550) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-05-20 17:24:10.757072) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wILTwZ9RKa/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.5H2QghSkHo/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:24:10 INFO - MultiFileLogger online at 20160520 17:24:10 in /builds/slave/test 17:24:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:24:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:24:10 INFO - {'append_to_log': False, 17:24:10 INFO - 'base_work_dir': '/builds/slave/test', 17:24:10 INFO - 'blob_upload_branch': 'Fx-Team', 17:24:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:24:10 INFO - 'branch': 'Fx-Team', 17:24:10 INFO - 'buildbot_json_path': 'buildprops.json', 17:24:10 INFO - 'config_files': ('talos/mac_config.py',), 17:24:10 INFO - 'default_actions': ('clobber', 17:24:10 INFO - 'read-buildbot-config', 17:24:10 INFO - 'download-and-extract', 17:24:10 INFO - 'populate-webroot', 17:24:10 INFO - 'create-virtualenv', 17:24:10 INFO - 'install', 17:24:10 INFO - 'run-tests'), 17:24:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:24:10 INFO - 'download_minidump_stackwalk': True, 17:24:10 INFO - 'download_symbols': 'ondemand', 17:24:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:24:10 INFO - 'tooltool.py': '/tools/tooltool.py', 17:24:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:24:10 INFO - '/tools/misc-python/virtualenv.py')}, 17:24:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:24:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:24:10 INFO - 'installer_path': 'installer.exe', 17:24:10 INFO - 'log_level': 'info', 17:24:10 INFO - 'log_name': 'talos', 17:24:10 INFO - 'log_to_console': True, 17:24:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:24:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:24:10 INFO - 'opt_config_files': (), 17:24:10 INFO - 'pip_index': False, 17:24:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:10 INFO - 'cmd': ('bash', 17:24:10 INFO - '-c', 17:24:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:10 INFO - 'enabled': True, 17:24:10 INFO - 'halt_on_failure': False, 17:24:10 INFO - 'name': 'check_screen_resolution'},), 17:24:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:10 INFO - 'cmd': ('bash', 17:24:10 INFO - '-c', 17:24:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:10 INFO - 'enabled': True, 17:24:10 INFO - 'halt_on_failure': False, 17:24:10 INFO - 'name': 'check_screen_resolution'},), 17:24:10 INFO - 'run_cmd_checks_enabled': True, 17:24:10 INFO - 'sps_profile': False, 17:24:10 INFO - 'sps_profile_interval': 0, 17:24:10 INFO - 'suite': 'dromaeojs', 17:24:10 INFO - 'system_bits': '32', 17:24:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:24:10 INFO - 'title': 't-yosemite-r7-0035', 17:24:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:24:10 INFO - 'use_talos_json': True, 17:24:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:24:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:24:10 INFO - 'work_dir': 'build'} 17:24:10 INFO - ##### 17:24:10 INFO - ##### Running clobber step. 17:24:10 INFO - ##### 17:24:10 INFO - Running pre-action listener: _resource_record_pre_action 17:24:10 INFO - Running main action method: clobber 17:24:10 INFO - rmtree: /builds/slave/test/build 17:24:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:24:11 INFO - Running post-action listener: _resource_record_post_action 17:24:11 INFO - ##### 17:24:11 INFO - ##### Finished clobber step (success) 17:24:11 INFO - ##### 17:24:11 INFO - ##### 17:24:11 INFO - ##### Running read-buildbot-config step. 17:24:11 INFO - ##### 17:24:11 INFO - Running pre-action listener: _resource_record_pre_action 17:24:11 INFO - Running main action method: read_buildbot_config 17:24:11 INFO - Using buildbot properties: 17:24:11 INFO - { 17:24:11 INFO - "project": "", 17:24:11 INFO - "product": "firefox", 17:24:11 INFO - "script_repo_revision": "production", 17:24:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:24:11 INFO - "repository": "", 17:24:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:24:11 INFO - "buildid": "20160520162836", 17:24:11 INFO - "pgo_build": "False", 17:24:11 INFO - "basedir": "/builds/slave/test", 17:24:11 INFO - "buildnumber": 16, 17:24:11 INFO - "slavename": "t-yosemite-r7-0035", 17:24:11 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:24:11 INFO - "platform": "yosemite_r7", 17:24:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:24:11 INFO - "branch": "fx-team", 17:24:11 INFO - "repo_path": "integration/fx-team", 17:24:11 INFO - "revision": "9804d9410b32b53e7fc6a497a6c3d731a78495a6", 17:24:11 INFO - "stage_platform": "macosx64", 17:24:11 INFO - "builduid": "12f81a960b0b4f8b928eb1eab627d70f", 17:24:11 INFO - "slavebuilddir": "test" 17:24:11 INFO - } 17:24:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 17:24:11 INFO - Running post-action listener: _resource_record_post_action 17:24:11 INFO - ##### 17:24:11 INFO - ##### Finished read-buildbot-config step (success) 17:24:11 INFO - ##### 17:24:11 INFO - ##### 17:24:11 INFO - ##### Running download-and-extract step. 17:24:11 INFO - ##### 17:24:11 INFO - Running pre-action listener: _resource_record_pre_action 17:24:11 INFO - Running main action method: download_and_extract 17:24:11 INFO - mkdir: /builds/slave/test/build/tests 17:24:11 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')]} 17:24:11 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:24:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:24:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:24:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 17:24:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 17:24:20 INFO - Downloaded 1199 bytes. 17:24:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:24:20 INFO - Using the following test package requirements: 17:24:20 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:24:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:24:20 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 17:24:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:24:20 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 17:24:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:24:20 INFO - u'jsshell-mac.zip'], 17:24:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:24:20 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 17:24:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:24:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:24:20 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 17:24:20 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:24:20 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 17:24:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:24:20 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 17:24:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:24:20 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 17:24:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:20 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:24:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:24:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:24:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 17:24:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 17:24:32 INFO - Downloaded 18630459 bytes. 17:24:32 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'] 17:24:32 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 17:24:35 INFO - Return code: 0 17:24:35 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 17:24: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')]} 17:24:35 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:24:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:24:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:24:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 17:24:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 17:24:37 INFO - Downloaded 18630459 bytes. 17:24:37 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'] 17:24:37 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 17:24:40 INFO - Return code: 0 17:24:40 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')]} 17:24:40 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:24:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:24:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:24:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 17:24:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 17:24:42 INFO - Downloaded 10840359 bytes. 17:24:42 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'] 17:24:42 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 17:24:42 INFO - Return code: 0 17:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:42 INFO - https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:24:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:24:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:24:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 17:24:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XEC0mkToTWmJAY2LSAG4Gg/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 17:24:45 INFO - Downloaded 110154276 bytes. 17:24:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:24:45 INFO - mkdir: /builds/slave/test/properties 17:24:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:24:45 INFO - Writing to file /builds/slave/test/properties/build_url 17:24:45 INFO - Contents: 17:24:45 INFO - build_url:https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:24:46 INFO - Running post-action listener: _resource_record_post_action 17:24:46 INFO - Running post-action listener: set_extra_try_arguments 17:24:46 INFO - ##### 17:24:46 INFO - ##### Finished download-and-extract step (success) 17:24:46 INFO - ##### 17:24:46 INFO - ##### 17:24:46 INFO - ##### Running populate-webroot step. 17:24:46 INFO - ##### 17:24:46 INFO - Running pre-action listener: _resource_record_pre_action 17:24:46 INFO - Running main action method: populate_webroot 17:24:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:24:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:24:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:24:46 INFO - '--tppagecycles', 17:24:46 INFO - '7'], 17:24:46 INFO - 'tests': ['tsvgm']}}, 17:24:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:24:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:24:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:24:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:24:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:46 INFO - 'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:24:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:24:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:46 INFO - 'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['damp', 'tps']}, 17:24:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:46 INFO - 'tests': ['damp', 'tps']}, 17:24:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['dromaeo_dom']}, 17:24:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:24:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['a11yr', 17:24:46 INFO - 'ts_paint', 17:24:46 INFO - 'tpaint', 17:24:46 INFO - 'sessionrestore', 17:24:46 INFO - 'sessionrestore_no_auto_restore', 17:24:46 INFO - 'tabpaint']}, 17:24:46 INFO - 'other-e10s': {'tests': ['a11yr', 17:24:46 INFO - 'ts_paint', 17:24:46 INFO - 'tpaint', 17:24:46 INFO - 'sessionrestore', 17:24:46 INFO - 'sessionrestore_no_auto_restore', 17:24:46 INFO - 'tabpaint']}, 17:24:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:24:46 INFO - 'ts_paint', 17:24:46 INFO - 'tpaint', 17:24:46 INFO - 'sessionrestore', 17:24:46 INFO - 'sessionrestore_no_auto_restore', 17:24:46 INFO - 'tabpaint']}, 17:24:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:24:46 INFO - 'ts_paint', 17:24:46 INFO - 'tpaint', 17:24:46 INFO - 'sessionrestore', 17:24:46 INFO - 'sessionrestore_no_auto_restore', 17:24:46 INFO - 'tabpaint']}, 17:24:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['a11yr', 17:24:46 INFO - 'ts_paint', 17:24:46 INFO - 'tpaint', 17:24:46 INFO - 'sessionrestore', 17:24:46 INFO - 'sessionrestore_no_auto_restore', 17:24:46 INFO - 'tabpaint']}, 17:24:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['a11yr', 17:24:46 INFO - 'ts_paint', 17:24:46 INFO - 'tpaint', 17:24:46 INFO - 'sessionrestore', 17:24:46 INFO - 'sessionrestore_no_auto_restore', 17:24:46 INFO - 'tabpaint']}, 17:24:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['tsvgx', 17:24:46 INFO - 'tsvgr_opacity', 17:24:46 INFO - 'tart', 17:24:46 INFO - 'tscrollx', 17:24:46 INFO - 'cart']}, 17:24:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:24:46 INFO - 'tsvgr_opacity', 17:24:46 INFO - 'tart', 17:24:46 INFO - 'tscrollx', 17:24:46 INFO - 'cart']}, 17:24:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:46 INFO - 'talos_options': ['--disable-e10s'], 17:24:46 INFO - 'tests': ['tp5o']}, 17:24:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:46 INFO - 'tests': ['tp5o']}, 17:24:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:46 INFO - 'talos_options': ['--disable-e10s', 17:24:46 INFO - '--xperf_path', 17:24:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:24:46 INFO - 'tests': ['tp5n']}, 17:24:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:46 INFO - 'talos_options': ['--xperf_path', 17:24:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:24:46 INFO - 'tests': ['tp5n']}}, 17:24:46 INFO - 'talos.zip': {'path': '', 17:24:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:24:46 INFO - Running post-action listener: _resource_record_post_action 17:24:46 INFO - ##### 17:24:46 INFO - ##### Finished populate-webroot step (success) 17:24:46 INFO - ##### 17:24:46 INFO - ##### 17:24:46 INFO - ##### Running create-virtualenv step. 17:24:46 INFO - ##### 17:24:46 INFO - Running pre-action listener: _resource_record_pre_action 17:24:46 INFO - Running main action method: create_virtualenv 17:24:46 INFO - Creating virtualenv /builds/slave/test/build/venv 17:24:46 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 17:24:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:24:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:24:46 INFO - Using real prefix '/tools/python27' 17:24:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:24:47 INFO - Installing distribute.............................................................................................................................................................................................done. 17:24:50 INFO - Installing pip.................done. 17:24:50 INFO - Return code: 0 17:24:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24:50 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 0x1077f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa991cac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, '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.wILTwZ9RKa/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.5H2QghSkHo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:24:50 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 17:24:50 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 17:24:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:24:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:50 INFO - 'HOME': '/Users/cltbld', 17:24:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:50 INFO - 'LOGNAME': 'cltbld', 17:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:50 INFO - 'MOZ_NO_REMOTE': '1', 17:24:50 INFO - 'NO_EM_RESTART': '1', 17:24:50 INFO - 'PAGER': '/bin/cat', 17:24:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:50 INFO - 'PWD': '/builds/slave/test', 17:24:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:50 INFO - 'SHELL': '/bin/bash', 17:24:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:24:50 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:24:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:50 INFO - 'USER': 'cltbld', 17:24:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:50 INFO - 'XPC_FLAGS': '0x0', 17:24:50 INFO - 'XPC_SERVICE_NAME': '0', 17:24:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:24:50 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 17:24:50 INFO - Cleaning up... 17:24:50 INFO - Return code: 0 17:24:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24:50 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 0x1077f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa991cac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, '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.wILTwZ9RKa/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.5H2QghSkHo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:24:50 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 17:24:50 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 17:24:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:24:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:50 INFO - 'HOME': '/Users/cltbld', 17:24:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:50 INFO - 'LOGNAME': 'cltbld', 17:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:50 INFO - 'MOZ_NO_REMOTE': '1', 17:24:50 INFO - 'NO_EM_RESTART': '1', 17:24:50 INFO - 'PAGER': '/bin/cat', 17:24:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:50 INFO - 'PWD': '/builds/slave/test', 17:24:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:50 INFO - 'SHELL': '/bin/bash', 17:24:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:24:50 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:24:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:50 INFO - 'USER': 'cltbld', 17:24:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:50 INFO - 'XPC_FLAGS': '0x0', 17:24:50 INFO - 'XPC_SERVICE_NAME': '0', 17:24:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:24:50 INFO - Downloading/unpacking psutil>=3.1.1 17:24:50 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 17:24:50 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 17:24:50 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 17:24:50 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 17:24:50 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 17:24:50 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 17:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:24:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:24:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:24:53 INFO - Installing collected packages: psutil 17:24:53 INFO - Running setup.py install for psutil 17:24:53 INFO - building 'psutil._psutil_osx' extension 17:24:53 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 17:24:54 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 17:24:54 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 17:24:54 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 17:24:54 INFO - building 'psutil._psutil_posix' extension 17:24:54 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 17:24:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:24:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:24:54 INFO - ^ 17:24:54 INFO - 1 warning generated. 17:24:54 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 17:24:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:24:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:24:54 INFO - Successfully installed psutil 17:24:54 INFO - Cleaning up... 17:24:55 INFO - Return code: 0 17:24:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa991cac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, '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.wILTwZ9RKa/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.5H2QghSkHo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:24:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:24:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:24:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:24:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:55 INFO - 'HOME': '/Users/cltbld', 17:24:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:55 INFO - 'LOGNAME': 'cltbld', 17:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:55 INFO - 'MOZ_NO_REMOTE': '1', 17:24:55 INFO - 'NO_EM_RESTART': '1', 17:24:55 INFO - 'PAGER': '/bin/cat', 17:24:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:55 INFO - 'PWD': '/builds/slave/test', 17:24:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:55 INFO - 'SHELL': '/bin/bash', 17:24:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:24:55 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:24:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:55 INFO - 'USER': 'cltbld', 17:24:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:55 INFO - 'XPC_FLAGS': '0x0', 17:24:55 INFO - 'XPC_SERVICE_NAME': '0', 17:24:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:24:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:24: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 17:24: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 17:24: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 17:24: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 17:24: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 17:24: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 17:24:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:24:57 INFO - Installing collected packages: mozsystemmonitor 17:24:57 INFO - Running setup.py install for mozsystemmonitor 17:24:57 INFO - Successfully installed mozsystemmonitor 17:24:57 INFO - Cleaning up... 17:24:57 INFO - Return code: 0 17:24:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:24: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')]} 17:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24: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')]} 17:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1077f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa991cac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, '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.wILTwZ9RKa/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.5H2QghSkHo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:24:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:24:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:24:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:57 INFO - 'HOME': '/Users/cltbld', 17:24:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:57 INFO - 'LOGNAME': 'cltbld', 17:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:57 INFO - 'MOZ_NO_REMOTE': '1', 17:24:57 INFO - 'NO_EM_RESTART': '1', 17:24:57 INFO - 'PAGER': '/bin/cat', 17:24:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:57 INFO - 'PWD': '/builds/slave/test', 17:24:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:58 INFO - 'SHELL': '/bin/bash', 17:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:24:58 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:58 INFO - 'USER': 'cltbld', 17:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:58 INFO - 'XPC_FLAGS': '0x0', 17:24:58 INFO - 'XPC_SERVICE_NAME': '0', 17:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:24:58 INFO - Downloading/unpacking blobuploader==1.2.4 17:24:58 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 17:24:58 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 17:24:58 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 17:24:58 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 17:24:58 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 17:24:58 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 17:25:00 INFO - Downloading blobuploader-1.2.4.tar.gz 17:25:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:25:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:25:00 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 17:25:00 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 17:25:00 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 17:25:00 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 17:25:00 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 17:25:00 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 17:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:25:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:01 INFO - Downloading docopt-0.6.1.tar.gz 17:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:25:01 INFO - Installing collected packages: blobuploader, requests, docopt 17:25:01 INFO - Running setup.py install for blobuploader 17:25:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:25:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:25:02 INFO - Running setup.py install for requests 17:25:02 INFO - Running setup.py install for docopt 17:25:02 INFO - Successfully installed blobuploader requests docopt 17:25:02 INFO - Cleaning up... 17:25:02 INFO - Return code: 0 17:25:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:02 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 0x1077f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa991cac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, '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.wILTwZ9RKa/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.5H2QghSkHo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:25:02 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 17:25:02 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 17:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:02 INFO - 'HOME': '/Users/cltbld', 17:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:02 INFO - 'LOGNAME': 'cltbld', 17:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:02 INFO - 'MOZ_NO_REMOTE': '1', 17:25:02 INFO - 'NO_EM_RESTART': '1', 17:25:02 INFO - 'PAGER': '/bin/cat', 17:25:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:02 INFO - 'PWD': '/builds/slave/test', 17:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:02 INFO - 'SHELL': '/bin/bash', 17:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:25:02 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:02 INFO - 'USER': 'cltbld', 17:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:02 INFO - 'XPC_FLAGS': '0x0', 17:25:02 INFO - 'XPC_SERVICE_NAME': '0', 17:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-9sZ7Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-kgIIWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-bxwf9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-8jiSev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-EgBwNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-1hywQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-sBcNRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-yhH_UZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-qS6EvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:03 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-G7hH4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:04 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Wz97Ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:04 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-07Ler1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:04 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ckMFt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:04 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Qd48VF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:04 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-fkm19d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:04 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-i9N55Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:04 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Mzsm0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:25:04 INFO - Running setup.py install for manifestparser 17:25:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:25:04 INFO - Running setup.py install for mozcrash 17:25:05 INFO - Running setup.py install for mozdebug 17:25:05 INFO - Running setup.py install for mozdevice 17:25:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:25:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:25:05 INFO - Running setup.py install for mozfile 17:25:05 INFO - Running setup.py install for mozhttpd 17:25:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:25:05 INFO - Running setup.py install for mozinfo 17:25:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:25:05 INFO - Running setup.py install for mozInstall 17:25:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:25:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:25:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:25:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:25:05 INFO - Running setup.py install for mozleak 17:25:06 INFO - Running setup.py install for mozlog 17:25:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:25:06 INFO - Running setup.py install for moznetwork 17:25:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:25:06 INFO - Running setup.py install for mozprocess 17:25:06 INFO - Running setup.py install for mozprofile 17:25:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:25:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:25:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:25:06 INFO - Running setup.py install for mozrunner 17:25:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:25:06 INFO - Running setup.py install for mozscreenshot 17:25:07 INFO - Running setup.py install for moztest 17:25:07 INFO - Running setup.py install for mozversion 17:25:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:25:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:25:07 INFO - Cleaning up... 17:25:07 INFO - Return code: 0 17:25:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25: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')]} 17:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:07 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 0x1077f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa991cac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, '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.wILTwZ9RKa/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.5H2QghSkHo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:25:07 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 17:25:07 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 17:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:07 INFO - 'HOME': '/Users/cltbld', 17:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:07 INFO - 'LOGNAME': 'cltbld', 17:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:07 INFO - 'MOZ_NO_REMOTE': '1', 17:25:07 INFO - 'NO_EM_RESTART': '1', 17:25:07 INFO - 'PAGER': '/bin/cat', 17:25:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:07 INFO - 'PWD': '/builds/slave/test', 17:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:07 INFO - 'SHELL': '/bin/bash', 17:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:25:07 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:07 INFO - 'USER': 'cltbld', 17:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:07 INFO - 'XPC_FLAGS': '0x0', 17:25:07 INFO - 'XPC_SERVICE_NAME': '0', 17:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:07 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-GFDMXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:07 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)) 17:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:07 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-8fICFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:07 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)) 17:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:07 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-jLWsvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:07 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)) 17:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:07 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-PUjEtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:08 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)) 17:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:08 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Rc0bd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:08 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)) 17:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:08 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-kNFI8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:08 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)) 17:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:08 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-tiUcjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:08 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)) 17:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:08 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-vZIvbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:08 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)) 17:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:08 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ogEe1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:08 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)) 17:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:08 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-alAkPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:08 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)) 17:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:08 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-sCgQdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:08 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)) 17:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:08 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-ItBILW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:08 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)) 17:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:08 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-Sugpo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:09 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)) 17:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:09 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-G_E5Z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:09 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)) 17:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:09 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-wFqKpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:09 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)) 17:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:09 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-hDf_L6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:09 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)) 17:25:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:09 INFO - Running setup.py (path:/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/pip-_SPUBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:09 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)) 17:25:09 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)) 17:25:09 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)) 17:25:09 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)) 17:25:09 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)) 17:25:09 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)) 17:25:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:25: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 17:25: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 17:25: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 17:25: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 17:25: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 17:25: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 17:25:11 INFO - Downloading blessings-1.6.tar.gz 17:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:25:11 INFO - Installing collected packages: blessings 17:25:11 INFO - Running setup.py install for blessings 17:25:12 INFO - Successfully installed blessings 17:25:12 INFO - Cleaning up... 17:25:12 INFO - Return code: 0 17:25:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:25:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:12 INFO - Reading from file tmpfile_stdout 17:25:12 INFO - Current package versions: 17:25:12 INFO - blessings == 1.6 17:25:12 INFO - blobuploader == 1.2.4 17:25:12 INFO - docopt == 0.6.1 17:25:12 INFO - manifestparser == 1.1 17:25:12 INFO - mozInstall == 1.12 17:25:12 INFO - mozcrash == 0.17 17:25:12 INFO - mozdebug == 0.1 17:25:12 INFO - mozdevice == 0.48 17:25:12 INFO - mozfile == 1.2 17:25:12 INFO - mozhttpd == 0.7 17:25:12 INFO - mozinfo == 0.9 17:25:12 INFO - mozleak == 0.1 17:25:12 INFO - mozlog == 3.2 17:25:12 INFO - moznetwork == 0.27 17:25:12 INFO - mozprocess == 0.22 17:25:12 INFO - mozprofile == 0.28 17:25:12 INFO - mozrunner == 6.11 17:25:12 INFO - mozscreenshot == 0.1 17:25:12 INFO - mozsystemmonitor == 0.3 17:25:12 INFO - moztest == 0.7 17:25:12 INFO - mozversion == 1.4 17:25:12 INFO - psutil == 3.1.1 17:25:12 INFO - requests == 1.2.3 17:25:12 INFO - wsgiref == 0.1.2 17:25:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:12 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 0x1077f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa991cac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, '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.wILTwZ9RKa/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.5H2QghSkHo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:25:12 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 17:25:12 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 17:25:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:25:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:12 INFO - 'HOME': '/Users/cltbld', 17:25:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:12 INFO - 'LOGNAME': 'cltbld', 17:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:12 INFO - 'MOZ_NO_REMOTE': '1', 17:25:12 INFO - 'NO_EM_RESTART': '1', 17:25:12 INFO - 'PAGER': '/bin/cat', 17:25:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:12 INFO - 'PWD': '/builds/slave/test', 17:25:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:12 INFO - 'SHELL': '/bin/bash', 17:25:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:25:12 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:25:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:12 INFO - 'USER': 'cltbld', 17:25:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:12 INFO - 'XPC_FLAGS': '0x0', 17:25:12 INFO - 'XPC_SERVICE_NAME': '0', 17:25:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:12 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)) 17:25:12 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)) 17:25:12 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)) 17:25:12 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)) 17:25:12 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)) 17:25:12 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)) 17:25:12 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)) 17:25:12 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)) 17:25:12 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)) 17:25:12 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)) 17:25:12 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)) 17:25:12 INFO - Cleaning up... 17:25:12 INFO - Return code: 0 17:25:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa991cac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, '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.wILTwZ9RKa/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.5H2QghSkHo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:25:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:25:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:25:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:12 INFO - 'HOME': '/Users/cltbld', 17:25:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:12 INFO - 'LOGNAME': 'cltbld', 17:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:12 INFO - 'MOZ_NO_REMOTE': '1', 17:25:12 INFO - 'NO_EM_RESTART': '1', 17:25:12 INFO - 'PAGER': '/bin/cat', 17:25:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:12 INFO - 'PWD': '/builds/slave/test', 17:25:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:12 INFO - 'SHELL': '/bin/bash', 17:25:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:25:12 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:25:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:12 INFO - 'USER': 'cltbld', 17:25:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:12 INFO - 'XPC_FLAGS': '0x0', 17:25:12 INFO - 'XPC_SERVICE_NAME': '0', 17:25:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:13 INFO - Downloading/unpacking jsonschema 17:25: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 17:25: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 17:25: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 17:25: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 17:25: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 17:25: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 17:25:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:25:15 INFO - Downloading/unpacking functools32 (from jsonschema) 17:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:15 INFO - Downloading functools32-3.2.3-2.tar.gz 17:25:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:25:15 INFO - warning: no files found matching '*.txt' 17:25:15 INFO - no previously-included directories found matching 'build' 17:25:15 INFO - no previously-included directories found matching 'dist' 17:25:15 INFO - no previously-included directories found matching '.git*' 17:25:15 INFO - Installing collected packages: jsonschema, functools32 17:25:16 INFO - Running setup.py install for functools32 17:25:16 INFO - warning: no files found matching '*.txt' 17:25:16 INFO - no previously-included directories found matching 'build' 17:25:16 INFO - no previously-included directories found matching 'dist' 17:25:16 INFO - no previously-included directories found matching '.git*' 17:25:16 INFO - Successfully installed jsonschema functools32 17:25:16 INFO - Cleaning up... 17:25:16 INFO - Return code: 0 17:25:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:25:16 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')]} 17:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:16 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')]} 17:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:16 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 0x1077f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077f9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa991cac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, '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.wILTwZ9RKa/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.5H2QghSkHo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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 17:25:16 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 17:25:16 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 17:25:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:25:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:16 INFO - 'HOME': '/Users/cltbld', 17:25:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:16 INFO - 'LOGNAME': 'cltbld', 17:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:16 INFO - 'MOZ_NO_REMOTE': '1', 17:25:16 INFO - 'NO_EM_RESTART': '1', 17:25:16 INFO - 'PAGER': '/bin/cat', 17:25:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:16 INFO - 'PWD': '/builds/slave/test', 17:25:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:16 INFO - 'SHELL': '/bin/bash', 17:25:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:25:16 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:25:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:16 INFO - 'USER': 'cltbld', 17:25:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:16 INFO - 'XPC_FLAGS': '0x0', 17:25:16 INFO - 'XPC_SERVICE_NAME': '0', 17:25:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:16 INFO - Downloading/unpacking flake8 17:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:25:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:25:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:25:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:25:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:25:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:25:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:25:20 INFO - no previously-included directories found matching 'docs/_build' 17:25:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:25:20 INFO - Running setup.py install for pep8 17:25:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:25:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:25:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:25:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:25:20 INFO - no previously-included directories found matching 'docs/_build' 17:25:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:25:20 INFO - Cleaning up... 17:25:20 INFO - Return code: 0 17:25:20 INFO - Running post-action listener: _resource_record_post_action 17:25:20 INFO - Running post-action listener: _start_resource_monitoring 17:25:20 INFO - Starting resource monitoring. 17:25:20 INFO - ##### 17:25:20 INFO - ##### Finished create-virtualenv step (success) 17:25:20 INFO - ##### 17:25:20 INFO - ##### 17:25:20 INFO - ##### Running install step. 17:25:20 INFO - ##### 17:25:20 INFO - Running pre-action listener: _resource_record_pre_action 17:25:20 INFO - Running main action method: install 17:25:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:20 INFO - Reading from file tmpfile_stdout 17:25:20 INFO - Detecting whether we're running mozinstall >=1.0... 17:25:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:25:20 INFO - Reading from file tmpfile_stdout 17:25:20 INFO - Output received: 17:25:20 INFO - Usage: mozinstall [options] installer 17:25:20 INFO - Options: 17:25:20 INFO - -h, --help show this help message and exit 17:25:20 INFO - -d DEST, --destination=DEST 17:25:20 INFO - Directory to install application into. [default: 17:25:20 INFO - "/builds/slave/test"] 17:25:20 INFO - --app=APP Application being installed. [default: firefox] 17:25:20 INFO - mkdir: /builds/slave/test/build/application 17:25:20 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'] 17:25:20 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 17:25:54 INFO - Reading from file tmpfile_stdout 17:25:54 INFO - Output received: 17:25:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:25:54 INFO - Running post-action listener: _resource_record_post_action 17:25:54 INFO - ##### 17:25:54 INFO - ##### Finished install step (success) 17:25:54 INFO - ##### 17:25:54 INFO - ##### 17:25:54 INFO - ##### Running run-tests step. 17:25:54 INFO - ##### 17:25:54 INFO - Running pre-action listener: _resource_record_pre_action 17:25:54 INFO - Running main action method: run_tests 17:25:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:25:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:25:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:25:54 INFO - 2016-05-20 17:25:54.400 screenresolution[1968:9138] starting screenresolution argv=screenresolution get 17:25:54 INFO - 2016-05-20 17:25:54.403 screenresolution[1968:9138] Display 0: 1600x1200x32@60 17:25:54 INFO - 2016-05-20 17:25:54.409 screenresolution[1969:9140] starting screenresolution argv=screenresolution list 17:25:54 INFO - Available Modes on Display 0 17:25:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:25:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:25:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:25:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:25:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:25:54 INFO - Intel Iris: 17:25:54 INFO - Chipset Model: Intel Iris 17:25:54 INFO - Type: GPU 17:25:54 INFO - Bus: Built-In 17:25:54 INFO - VRAM (Dynamic, Max): 1536 MB 17:25:54 INFO - Vendor: Intel (0x8086) 17:25:54 INFO - Device ID: 0x0a2e 17:25:54 INFO - Revision ID: 0x0009 17:25:54 INFO - Displays: 17:25:54 INFO - Display: 17:25:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:25:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:25:54 INFO - Display Serial Number: 1600x1200 60 17:25:54 INFO - Main Display: Yes 17:25:54 INFO - Mirror: Off 17:25:54 INFO - Online: Yes 17:25:54 INFO - Rotation: Supported 17:25:54 INFO - Return code: 0 17:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:25:54 INFO - Python 2.7.3 17:25:54 INFO - Return code: 0 17:25:54 INFO - grabbing minidump binary from tooltool 17:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:25:54 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 0x107697d30>, '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 0x1074bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10768eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:25:54 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 17:25:54 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 17:25:54 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 17:25:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:25:54 INFO - Return code: 0 17:25:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:25:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:25:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:25:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:25:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:25:55 INFO - Return code: 0 17:25:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--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 17:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0035 --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 17:25:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wILTwZ9RKa/Render', 17:25:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:55 INFO - 'HOME': '/Users/cltbld', 17:25:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:55 INFO - 'LOGNAME': 'cltbld', 17:25:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:25:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:55 INFO - 'MOZ_NO_REMOTE': '1', 17:25:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:25:55 INFO - 'NO_EM_RESTART': '1', 17:25:55 INFO - 'PAGER': '/bin/cat', 17:25:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:55 INFO - 'PWD': '/builds/slave/test', 17:25:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:25:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:55 INFO - 'SHELL': '/bin/bash', 17:25:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5H2QghSkHo/Listeners', 17:25:55 INFO - 'TMPDIR': '/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/', 17:25:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:55 INFO - 'USER': 'cltbld', 17:25:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:55 INFO - 'XPC_FLAGS': '0x0', 17:25:55 INFO - 'XPC_SERVICE_NAME': '0', 17:25:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0035', '--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 17:25:56 INFO - mozversion application_buildid: 20160520162836 17:25:56 INFO - mozversion application_changeset: 9804d9410b32b53e7fc6a497a6c3d731a78495a6 17:25:56 INFO - mozversion application_display_name: Nightly 17:25:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:25:56 INFO - mozversion application_name: Firefox 17:25:56 INFO - mozversion application_remotingname: firefox 17:25:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:25:56 INFO - mozversion application_vendor: Mozilla 17:25:56 INFO - mozversion application_version: 49.0a1 17:25:56 INFO - mozversion platform_buildid: 20160520162836 17:25:56 INFO - mozversion platform_changeset: 9804d9410b32b53e7fc6a497a6c3d731a78495a6 17:25:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:25:56 INFO - mozversion platform_version: 49.0a1 17:25:56 INFO - using testdate: 1463790356 17:25:56 INFO - actual date: 1463790356 17:25:56 INFO - starting webserver on 'localhost:49392' 17:25:56 INFO - SUITE-START | Running 2 tests 17:25:56 INFO - TEST-START | dromaeo_css 17:25:56 INFO - operating with platform_type : mac_ 17:25:56 INFO - Initialising browser for dromaeo_css test... 17:25:56 INFO - TEST-INFO | started process 1987 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpdzpqTE/profile http://localhost:49392/getInfo.html) 17:25:58 INFO - PROCESS | 1987 | __metrics Screen width/height:1600/1200 17:25:58 INFO - PROCESS | 1987 | colorDepth:24 17:25:58 INFO - PROCESS | 1987 | Browser inner width/height: 1024/689 17:25:58 INFO - PROCESS | 1987 | __metrics 17:25:58 INFO - PROCESS | 1987 | 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" 17:25:58 ERROR - PROCESS | 1987 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:25:59 INFO - TEST-INFO | 1987: exit 0 17:25:59 INFO - Browser initialized. 17:25:59 INFO - Running cycle 1/1 for dromaeo_css test... 17:25:59 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpdzpqTE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:11 INFO - PROCESS | 1991 | RSS: Main: 238657536 17:26:11 INFO - PROCESS | 1991 | 17:27:02 INFO - PROCESS | 1991 | 1463790422618 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] 17:29:12 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49392/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49392/tests/dromaeo/cssquery-ext.html) 17:29:13 INFO - PROCESS | 1991 | RSS: Main: 338612224 17:29:13 INFO - PROCESS | 1991 | 17:32:16 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49392/tests/dromaeo/cssquery-ext.html (next: http://localhost:49392/tests/dromaeo/cssquery-jquery.html) 17:32:17 INFO - PROCESS | 1991 | RSS: Main: 375087104 17:32:17 INFO - PROCESS | 1991 | 17:35:19 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49392/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49392/tests/dromaeo/cssquery-mootools.html) 17:35:20 INFO - PROCESS | 1991 | RSS: Main: 369897472 17:35:20 INFO - PROCESS | 1991 | 17:38:22 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49392/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49392/tests/dromaeo/cssquery-prototype.html) 17:38:22 INFO - PROCESS | 1991 | RSS: Main: 365301760 17:38:22 INFO - PROCESS | 1991 | 17:41:26 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49392/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49392/tests/dromaeo/cssquery-yui.html) 17:41:26 INFO - PROCESS | 1991 | RSS: Main: 336744448 17:41:26 INFO - PROCESS | 1991 | 17:44:29 INFO - PROCESS | 1991 | Cycle 1(1): loaded http://localhost:49392/tests/dromaeo/cssquery-yui.html (next: http://localhost:49392/tests/dromaeo/cssquery-dojo.html) 17:44:30 INFO - PROCESS | 1991 | RSS: Main: 384962560 17:44:30 INFO - PROCESS | 1991 | 17:44:30 INFO - PROCESS | 1991 | __start_tp_report 17:44:30 INFO - PROCESS | 1991 | _x_x_mozilla_page_load 17:44:30 INFO - PROCESS | 1991 | _x_x_mozilla_page_load_details 17:44:30 INFO - PROCESS | 1991 | |i|pagename|runs| 17:44:30 INFO - PROCESS | 1991 | |0;dojo.html;2979.553067039944;3395.7622966392346;3426.725861931046;3469.5663042119736;3490.9825450872736;5432.375276843164;5558.666480011202;5662.235598194243;5760.827175184741;5781.768729250835;5665.971670141644;5729.914051289234;5791.015527360349;5797.536197104232;5825.213596164516;5155.510226528474;5186.988537235241;5233.058049551079;5251.711155886424;5334.866628334284;5292.947070529303;5335.306410166673;5344.35867695877;5375;5375.865603359908;5137.743112844361;5196.402413722432;5212.921806172911;5251.2123181523075;5261.500157485033;5184.274201611777;5227.973860130713;5230.8169214077325;5305.761240744176;5340.198970154469;5156.20078887773;5222.556082732972;5225.843224703265;5227;5292.391374991887;4696.835610753607;5657.51911087554;5668.263125793662;5716.056850619642;5726.112452569859;6086.765601141693;6146.8770624587705;6152.984757515004;6222.782202622931;6263.87472250557;6264.123022776815;6293.96853015732;6298.3701629837105;6324.462420694201;6350.26971898233;5664.971675141598;5848.67832269225;5873.911891321633;5909.23179986599;5913.822585322448;6203.193584833943;6254.9687251563455;6260.154879091356;6271.937280627135;6324;5962.9043225348305;6170.10533472653;6184.845378865564;6198.597091189148;6200.720967556471;6129.847850912876;6287.1198032275515;6295.716692748746;6303.432691057735;6308.810735677937;5781.4507621775865;6127.097902969389;6193.194884664965;6242.063690446469;6251.874962500725;6188.257409110935;6228.6574238416415;6274.466670333073;6300.7479700811455;6335.809925702236;4898.632602554822;4930.852074437773;4994.700317980933;5002.299678045077;5027.371578552681;4502.077074199781;4523.23105072132;4524.660650451229;4548.385967894358;4549.976255342588;5682.744276507485;5695.971520142372;5719.571032172604;5740.827775166752;5831.708414579339;6314.494840412756;6324;6324.683765811691;6329.3354197809485;6339.2392912850755;5736.62711923732;5743.310802703702;5757.424257574209;5769.249997500382;5773.451522105393;5286.36563612369;5383.16560933056;5383.488568585978;5420.674759514442;5440.945590544044;6360.713767880364;6406.615603063738;6407.455366293824;6411.230652321751;6425.903611445832;6297.2128483939505;6314.084457753508;6332.683365831782;6336.619802811846;6343.460805831556;6198.969005154945;6258.311585725475;6268.529860260407;6390.7443702251385;6420.839479013062;6182.659953702409;6196.380361963767;6201.968990155201;6204.937950620617;6217.626942383471;6110.7106460192745;6249.375062493896;6259.280182779039;6271.749130034747;6291.811245662638;6006.339302676614;6016.969915150396;6056.485198758155;6119.173911522065;6130.754769809158;6071.787487437919;6091.451769340781;6116.66358350295;6130.877382452415;6151.26184857802;5777.45114214149;5860.501857342174;5916.023856063874;5935.495482883831;5942.762289508371;6060.818175454743;6076.422739839708;6112.8777424452155;6157.0456579230295;6159.753609855556;5824.912626310524;5833.445822646841;5889.057750759858;5963.463288304073;6012.488938440281 17:44:30 INFO - PROCESS | 1991 | |1;ext.html;491.9754012299384;501.3282201849522;501.44590227798307;502.9287617374991;504;30564.930227442044;34359.484607730905;36767.08082297944;43383;45501;43772.56227437725;44293.7785311073;44362.778186109026;44398.55601443985;44528.777356113176;8666.783330416727;9549.904500954972;9664.468454235015;9747.415155090683;11701.59044433445;11564.768704625945;11770;11877.227980181304;11883.989860861837;11889.583864564742;11848.526058957632;11865.881341186563;11877.049836013099;11896.345700986441;11903.92864642182;8695.391322607451;11650.475728592233;11913.523459061627;11931.343776092312;11932.448781658415;11727.765444691144;11736.589219377282;11744.882551174464;11887.227330223508;12016.939915300454;40344.798276008434;41215.1756964859;41256.38115428271;41637;41641.95895102646;3112.8910488133033;3350.6816852398983;3365.175531994677;3392.5589673342556;3768.5289338832645;29485.557716634266;29525;29701.108966731033;30375.544366834514;30692.53961190584;2767.8200916940336;2769.8615069246457;2830.0236418435607;2836.773058155343;2842.1331493894354;483905;493384;497582;497591;497698.5115074438;34454.13864653404;35351.823240883634;53475;65291.67354163294;65445.345546543926;36755.264894701955;36805.63194368075;36816.26367472689;36833.894983150545;36966.445503317475;15088.170150641601;15228.162451065076;15234.933554651068;15239.695206096038;15245.695086098216;11560.248583842023;11712.238704484182;12638.747225055631;12659.177153484994;12889.226646401246;7752.26353496417;7939.841203175904;7961.004874390701;7998.64006119726;8009.959950200212;3278.0821370016433;3281.2125089978713;3286.4905939578925;3289.210589458513;3313.867445302161;996.476849653923;1001.3140998416109;1032.8037672842136;1081.291753901196;1092.7213560542011;30386.84806575953;31106.377872442423;32129.357412851612;32366.676333236366;33073.50389744156;1036.5439206749156;1042.7288904884786;1060.7242117049623;1081.9404932728776;1091.1925175370168;12940.611781646565;15190.848091519165;15246.923765381323;15400.537983860502;15505.84494155044;690146;692121;695267;696548;699333;60506.697466512385;60898.69550652307;61128.77742445126;61325;61574;1322.9536966206138;1447.4789075932656;1454.1057249791302;1477.5567329801017;1495.588713103905;24131.155409560582;30273.697263027087;30598.847005765718;30936.53595196029;31883;52957.20564191463;53445.46554534561;53779.46220537901;53838.92322153457;53923.73038134784;583910;586950;589500;589770;590140;21457.678134828304;22104.115835367047;25754;25809;25904.740952590986;45121;45385.092298154515;46061;46185;47104;39689.60310396859;39734;40183;40723.79638101909;41011;37824.054398640255;38989;39110.022249443995;39741;40054.399184012844;37702.62297376991;38545.61454385532;38905.61094389133;39408.62069827542;40920;28450.6085931964;31805.840970794998;34194.658053419145;34333.14167145841;34467.655323446445;20581;25100.62349064729;25158.49683006293;25853.482930340913;25861.870690646425 17:44:30 INFO - PROCESS | 1991 | |2;jquery.html;386.79113278829453;387.73176915046963;390.91595307008987;391.4695587478966;410.38032570818075;13292.468301267962;19605.313814016496;20084.995750212503;21182.788172118275;21186.152553897824;20557.897210513926;20803.063862126204;20943.47641308968;21157.365279041653;21736.239231626896;5814.7092645367775;6037.154798328237;6073.331933487335;6088.086786981944;6089.725962331683;5425.6202065855405;5963.284405871302;5966.910496342558;6059.272887253535;6097.359777223394;5859.44335288147;5979.730912108966;5995.700214989233;6001.159837622737;6006.699665016754;5702.287214098238;5819.709014549277;5832.825015249549;5887;5988.820335389945;5120.462351453119;5135.6148288878485;5148.176291793333;5156.458571849977;5157.30376399185;24760.752392476024;25855.74144258571;25862.870685646452;26054.478910421876;26670.06654767074;2296.7014288142436;2482.8758562071826;2539.4286285585595;2605.387733882536;2641.77544908683;14974.925125374413;15043;15170.013949093383;15239.847601523954;15896.76154857678;1740.0951505217342;1785.4108144312345;1811.4837271377726;1854.0729635182408;1870.9345172918881;121540;123238;124015;124168.1374779379;126094.36952815177;7761.068671759425;9363.90636093644;11217.71955701114;11253.774924501628;11314.660560183207;11615.30308181495;12146.757064858653;12794.360281985864;12886.806697899352;13893.791593125909;8391.202835730595;8452.450590711584;8601.483910965362;8758;8806.471611703319;8700.78248043804;8735.825283494294;8744.956275218583;8820.603072861737;8851.380403371832;5692.316921969307;6672.466202703772;6787.321267873272;6797.762078327334;6813.965930170318;1851.6019055903046;1882.1436246507806;1882.5575989642423;1887.3016983716113;1888.2258274107824;920.6179435534178;979.4025644356936;1009.1371877045168;1043.6555936540924;1056.6671498477954;18761.780484268478;20920;21419.92900354976;21512.031958561904;21571.2450064247;946.2619157057507;957.899420560845;1006.2603986070233;1012.8379459286626;1038.445424256323;8371.744238364294;8541.974963004355;9629.566669499749;10542.314749541256;11359.886401135884;583451;585762;593907;602742;614830;114739;117786.51405822345;118938;119979;120232.39883800525;975.2149519636619;996.785691076422;1054.1408751867205;1067.530286673861;1068.3483075324045;18236.544086397946;18997.715034274774;19211.32760353381;19231.51921201981;19366;48734.75632621814;49298.7815548294;51338;53129.734351328;54662;79459;81362;81600.59199703964;81752;83366.58316708621;7757.26306000929;8078.515289082674;8125.959370203348;8243.464174828618;8381.622826972924;5856.150858125633;7368.521046131984;12414.379281035732;12577.119601627799;12646.747065058831;8333.874991875005;11087.72280692989;11262.718432039264;11308.378039207593;11382.487788049391;7999.920010798701;10700.946495267473;10791.1367090631;10956.013958743433;11108.166887483632;11019.614313498989;11259.142241530055;11274.943625281821;11293.887061129284;11598.826017609912;6564.540482166202;8134.75595732129;8246.587670616565;8367.623456944573;8459.196376344238;10943.030254553938;10967.341959482457;11235.66293011211;11294.43527823622;11313.71715707114 17:44:30 INFO - PROCESS | 1991 | |3;mootools.html;1356.7829147336429;1571.4028669105737;1604.6068713165273;1795.955101122472;1928.9228430862765;6531.510136739746;8582.785430364236;8761;8784.604692788824;8820.8676869847;8514.574271286427;8603.397762156652;8702.869456958135;8703.825923481527;8719.912800871989;1934.5260411199276;2098.727165468487;2354.9175778847743;2386.200622791367;2393.7965272951733;2386.188695843413;2391.330427480308;2394.988025059881;2400.8319417640773;2424.4302588891596;2366.5858474766933;2387.415083304584;2401.927942161738;2403.843750156243;2407.4823912858733;2096.2767845093417;2303.343547089088;2346.2257455039794;2375.7624237576106;2381.8570885746913;2387.6418537219383;2403.9879800600884;2404.8557086574688;2410.3251089694913;2410.6384042393606;7428.962855185743;8615;8677.305815534743;8681.782955426102;8727;5128.538431541179;5165.354330708661;5229.921551176735;5291.444398338158;5339.199120131972;2853.8145020573597;3396;3521.29574085184;3544.6987006104255;3553.9111522211892;1009.9646512372105;1017.6692574913183;1018.241410149436;1021.4433133942019;1034.953427095775;11542.769144617061;11544.884551154464;11563.710907227387;11564.363959982198;11569.768604627945;4689.507601701772;4735.881602959885;4744.074905393415;4804;4817.759112044384;9756.853647195298;9786.314957952874;9892.455914924749;9897.60409583623;9908;5342.866428339323;5343.893122137535;5350.063738845686;5354.170103633943;5372.838814835562;4411.558844115627;4584.931226031612;4760.2625041860165;4925.679830810986;4938.481459446778;3924.4898163238595;3968.464257325282;4026.0941288210506;4045.110075783323;4067.613576710208;1253.9686507837196;1283.7560863435917;1286.202554416249;1298.9870101298866;1299.7465494228725;656.2846497317946;745.2324106170653;750.8573371059481;836.393614629401;933.3932943586723;4771.618270538349;5332.573394128461;5638.633488823213;5759.280089988752;5842.065269556851;948.4167237149242;956.5599824081;966.3815158298695;979.872616559857;982.1259079419323;3788.962110378916;3909.4722212500947;3910.3156947534067;3921.0197450637343;3923.430153249626;10455.059044686015;10585.682429527127;10809.35143891369;10899.346039237671;10957.83563246552;10751.44104104916;10782.245242832927;10878.67363979066;10883.18376121794;10914.508847101899;7875.881861772078;8187.508749474931;8260.256576908227;8280.71017514384;8326.375521835887;5696.20253164549;5729.28383952006;5907.645541267538;5930.347661757117;5937.703114844327;9690.418574885529;9713.951430242803;10016.298859079794;10121.139703125022;10236.078752912274;9826.476896081118;10051.648192313234;10054.49727513636;10251.641192558225;10273.178145748172;7460.925390746023;7488.176300607038;7515.13575938774;7518.060242469692;7548.094228692592;8186.754397368089;8212.589370531568;8223.671053157806;8276.29651479631;8286.047104582809;7197.424206063398;7400.000999865165;7459.142198647008;7510.924890751023;7522.473426860067;7193.280671932765;7240;7269.818254543678;7429.219931907072;7445.069366329209;6812.216595091628;6854.554453960476;6925.3074692530345;6942.166939967236;6978.127734033246;4644.256918892961;4773.212419950669;4873.853784386474;4899.167141585867;4904.215325547895;4900.8774780630765;4945.777440015136;4965.925511117408;5005.374328208974;5005.399352077774 17:44:30 INFO - PROCESS | 1991 | |4;prototype.html;304.8780487804878;312.65451675898134;316.0518444666002;320.42003972809215;320.44722853078457;4527.366168736371;4534.455865296165;5367.275417818593;5388.892222155555;5431.755570999305;4555.9544404555945;5281.63028587999;5381;5387.272718183054;5417.753916599175;3354.614219364768;3679.5530971903654;3841.8847434576937;3886;3964.3062464068817;3699;3927.725059245854;3945.487086678728;4016.417619445179;4056.8580099696514;3383.847726852285;3707.2956138333766;4030;4051.412545180947;4059.918801623966;3798.8480460781534;3828.827702753355;3886.455896174524;4075.8777236682945;4085.9591404085877;2949.9262518436994;3049.923751906198;3146.716795488417;3526.717862570988;3554.2180720241763;4249.957500424987;4892.486288939645;4916.385451818523;4933.80264789408;5105.447276361819;3780.8865734028022;4347.282698354799;4612.469565999886;4649.209634362132;4692.577668009862;4956.653034287601;5235.424103348629;5353.57171426285;5560.972195139039;5607.831765047055;3915.725899187058;3919.431682406064;3966.3257246268204;3966.6231707987695;3975.284448799215;5138.486151384832;5382.434844341366;5487.07952216725;5578.437251182052;5623.831285061455;3344.6822551857535;3504.2641045380246;3578.8884558110317;3662.50556174913;3662.8278950735507;3405.9148521287166;3594.6225646307284;4024.8931543825684;4052.615001574846;4062.756234625873;3690.538682664667;4091.345384738487;4376.934345984812;4668.089722504079;4687.296905464208;4260.913467065879;5291.735413229323;5483.80488925972;5643.379228284885;5667.603267771216;1218.9577910886242;1272.6691060324383;1386.986130138706;1388.2642199634008;1389.861013898602;780.4653812138703;824.8721448175543;833.6957010691054;876.2157868707471;877.3025444771422;4501.897035226325;4608.331791890158;5183.377994640668;5245.186996015624;5267.683938963674;4717.787699553528;5600.579956503197;5780.306363236438;5803.850247073278;5817.825465236049;4639.884002899887;4986.7257300848805;5031.547160755476;5036.748162591929;5109.693418394908;2696.4091186200235;2870.7129287071543;2954.3648115655237;2991.9850400747855;3027.3945210957454;5874.970625146847;6012;6043.607165534227;6066.787662431793;6085.878282434326;5301.68189908599;5552.7223638817895;5886.293644762656;5905.704714764244;5968.37332080134;5966.151832068292;6019.608725432833;6023.397660233942;6042.305134909541;6083.7870674526175;5554.472325129106;5682.18093596373;5702.488840457199;5804.372267332917;5836.533077353886;4852.51474852526;5029.899402011866;5592.972035139961;5704.572157088152;5722.313322401172;5174.8965020700125;6000.729967151401;6009.278886533469;6067.089936509382;6085.2393450818645;3274.1159886830897;3834.4087203811223;3945.6054394560315;3973.145773658677;3987.986067036128;4637.536246375335;4742.573168414721;5222.55608273301;5491.862703432446;5755.424457554211;4824.638152138534;5193.76628051731;5197.688138711689;5615.494605485363;5673.220122140204;4579;5273.367195936512;5549.445055494418;5587.525060369914;5623.915641265467;4772.186569103857;5302.886393857333;5413.024246150048;5446.121088224556;5453.291072160752;4808.124831315719;5309.57523398135;5379.973100134474;5466.590005749665;5622.734884650921;5201.869953251199;5454.754536045808;5467.6719396836315;5470.316210473691;5481.232627432242 17:44:30 INFO - PROCESS | 1991 | |5;yui.html;675.8242856857217;703.771274335841;705.4567982653357;713.2546488919079;724.235931092697;14777.778333324995;16888.493345199648;17428.82571174288;17439;17444.215010324533;14426;14433.206173660445;14480.986330956837;14490.5652830415;14505.274736263198;732.092205664977;749.4641331448014;927.8700981862545;932.5570354081825;937.5687183895416;902.3548163063408;919.6229545886188;925.1950802801572;936.5691781780355;937.6999360204737;934.2572654739498;948.3219498058886;959.5586030426012;960.7357976556433;961.4231461123305;767.3362541401648;942.6465075596651;957.7031120352678;958.7842735384518;961.293449314753;726.0343742822034;728.6247582494966;729.4930023633577;730.1092666946317;730.6492883415937;14664.780028299583;14726.852731472656;14837.332320045516;14929.40282388714;15041.097534147986;12021.759564808743;12073.215241009306;12168.756624867541;12333.260004399765;12361.814572781326;10807.783844323149;10942.288751231225;11041.447927603587;11486.185884458278;11665.24175928562;4024.9195016099516;4044.919101617951;4048.0891799345204;4147.39862719907;4185.66514678828;81401.59299203525;81973;83330.16669833285;83988;84132.57933710354;9246.445213287225;9287.071292870794;9325.533723313943;9515.096065873731;9544.570494327772;17405.564860878327;20111.497212569804;20318.9840507974;20529.692054619194;20549.58900821975;7099.503034787618;7486.475946683789;7505.774826755206;7550.433717471212;7716.344110750537;4025.879223623296;4120.299549076664;4147.3986271991;4162.5629308922735;4163.770992595436;3450.9309813803584;3462.376772181032;3547.6807087362263;3555.1112221944513;3568.9643103568633;615.9630422174683;633.1452539072308;638.7221558622015;640.5836206465741;641.6919878458273;481.17478524330244;501.89962007598626;504.39220739009403;574.2764117212259;587.9147523609053;7812.57030863308;8038.678452861937;8128.349732021542;8169.795755106051;8263.256306932404;574.7930744931821;598.2312728144313;609.1502354215844;610.8808782287412;625.6621424430756;3853.845846166178;3855.20968201518;3857.1514266861245;3952.505936757905;4021.792966815255;90376.09623903809;90674;91373.17253655022;92397.07602923884;92478.53760731287;69495.65252173707;74062.62968685121;79402.6029869847;80681.19318806854;80805.59597201976;68096;68382.65808670924;69104.3089569098;71321.64339178374;71763.6411817948;7610.277023682741;7691.884676721956;7803.609819508888;7874.448788584744;7902.24928631779;39818.80090599528;40199.59800401959;43240.91897702582;43268.35097473478;43840;56500;62280.68859655673;62579.6871015642;63062.68468657627;63524;9987.700368988943;10153.949230253802;10316.658834351787;10734.51694673726;10980.780384392427;11423.378145501174;12099.395030248628;12178.939105304416;12260.75478490443;12379.319137447526;11524.481398336928;11739;11770.705732356417;11793.11551633648;11830.11274154459;11344.502420629573;11600.361980091177;11739;12354.567590134302;12437.378131093228;11159.944200278947;11276.379799111128;11302.321860688386;11309.377984210947;11525.758096575955;9496.47769372691;9585.329026968324;9664.95167524186;9679.467629280458;9718.368306060083;7889.368850491832;9540.570674319533;9584.137427631267;9762.267829912927;9816.31285810015 17:44:30 INFO - PROCESS | 1991 | __end_tp_report 17:44:30 INFO - PROCESS | 1991 | __start_cc_report 17:44:30 INFO - PROCESS | 1991 | _x_x_mozilla_cycle_collect,1095 17:44:30 INFO - PROCESS | 1991 | __end_cc_report 17:44:30 INFO - PROCESS | 1991 | __startTimestamp1463791470022__endTimestamp 17:44:30 INFO - PROCESS | 1991 | 17:44:30 INFO - PROCESS | 1991 | ------- Summary: start ------- 17:44:30 INFO - PROCESS | 1991 | Number of tests: 6 17:44:30 INFO - PROCESS | 1991 | 17:44:30 INFO - PROCESS | 1991 | [#0] dojo.html Cycles:165 Average:5762.18 Median:5935.50 stddev:640.20 (10.8%) stddev-sans-first:603.80 17:44:30 INFO - PROCESS | 1991 | Values: 2979.6 3395.8 3426.7 3469.6 3491.0 5432.4 5558.7 5662.2 5760.8 5781.8 5666.0 5729.9 5791.0 5797.5 5825.2 5155.5 5187.0 5233.1 5251.7 5334.9 5292.9 5335.3 5344.4 5375.0 5375.9 5137.7 5196.4 5212.9 5251.2 5261.5 5184.3 5228.0 5230.8 5305.8 5340.2 5156.2 5222.6 5225.8 5227.0 5292.4 4696.8 5657.5 5668.3 5716.1 5726.1 6086.8 6146.9 6153.0 6222.8 6263.9 6264.1 6294.0 6298.4 6324.5 6350.3 5665.0 5848.7 5873.9 5909.2 5913.8 6203.2 6255.0 6260.2 6271.9 6324.0 5962.9 6170.1 6184.8 6198.6 6200.7 6129.8 6287.1 6295.7 6303.4 6308.8 5781.5 6127.1 6193.2 6242.1 6251.9 6188.3 6228.7 6274.5 6300.7 6335.8 4898.6 4930.9 4994.7 5002.3 5027.4 4502.1 4523.2 4524.7 4548.4 4550.0 5682.7 5696.0 5719.6 5740.8 5831.7 6314.5 6324.0 6324.7 6329.3 6339.2 5736.6 5743.3 5757.4 5769.2 5773.5 5286.4 5383.2 5383.5 5420.7 5440.9 6360.7 6406.6 6407.5 6411.2 6425.9 6297.2 6314.1 6332.7 6336.6 6343.5 6199.0 6258.3 6268.5 6390.7 6420.8 6182.7 6196.4 6202.0 6204.9 6217.6 6110.7 6249.4 6259.3 6271.7 6291.8 6006.3 6017.0 6056.5 6119.2 6130.8 6071.8 6091.5 6116.7 6130.9 6151.3 5777.5 5860.5 5916.0 5935.5 5942.8 6060.8 6076.4 6112.9 6157.0 6159.8 5824.9 5833.4 5889.1 5963.5 6012.5 17:44:30 INFO - PROCESS | 1991 | 17:44:30 INFO - PROCESS | 1991 | [#1] ext.html Cycles:180 Average:71119.09 Median:18793.61 stddev:160263.00 (852.8%) stddev-sans-first:160624.85 17:44:30 INFO - PROCESS | 1991 | Values: 492.0 501.3 501.4 502.9 504.0 30564.9 34359.5 36767.1 43383.0 45501.0 43772.6 44293.8 44362.8 44398.6 44528.8 8666.8 9549.9 9664.5 9747.4 11701.6 11564.8 11770.0 11877.2 11884.0 11889.6 11848.5 11865.9 11877.0 11896.3 11903.9 8695.4 11650.5 11913.5 11931.3 11932.4 11727.8 11736.6 11744.9 11887.2 12016.9 40344.8 41215.2 41256.4 41637.0 41642.0 3112.9 3350.7 3365.2 3392.6 3768.5 29485.6 29525.0 29701.1 30375.5 30692.5 2767.8 2769.9 2830.0 2836.8 2842.1 483905.0 493384.0 497582.0 497591.0 497698.5 34454.1 35351.8 53475.0 65291.7 65445.3 36755.3 36805.6 36816.3 36833.9 36966.4 15088.2 15228.2 15234.9 15239.7 15245.7 11560.2 11712.2 12638.7 12659.2 12889.2 7752.3 7939.8 7961.0 7998.6 8010.0 3278.1 3281.2 3286.5 3289.2 3313.9 996.5 1001.3 1032.8 1081.3 1092.7 30386.8 31106.4 32129.4 32366.7 33073.5 1036.5 1042.7 1060.7 1081.9 1091.2 12940.6 15190.8 15246.9 15400.5 15505.8 690146.0 692121.0 695267.0 696548.0 699333.0 60506.7 60898.7 61128.8 61325.0 61574.0 1323.0 1447.5 1454.1 1477.6 1495.6 24131.2 30273.7 30598.8 30936.5 31883.0 52957.2 53445.5 53779.5 53838.9 53923.7 583910.0 586950.0 589500.0 589770.0 590140.0 21457.7 22104.1 25754.0 25809.0 25904.7 45121.0 45385.1 46061.0 46185.0 47104.0 39689.6 39734.0 40183.0 40723.8 41011.0 37824.1 38989.0 39110.0 39741.0 40054.4 37702.6 38545.6 38905.6 39408.6 40920.0 28450.6 31805.8 34194.7 34333.1 34467.7 20581.0 25100.6 25158.5 25853.5 25861.9 17:44:30 INFO - PROCESS | 1991 | 17:44:30 INFO - PROCESS | 1991 | [#2] jquery.html Cycles:180 Average:34914.64 Median:14234.13 stddev:99472.48 (698.8%) stddev-sans-first:99717.74 17:44:30 INFO - PROCESS | 1991 | Values: 386.8 387.7 390.9 391.5 410.4 13292.5 19605.3 20085.0 21182.8 21186.2 20557.9 20803.1 20943.5 21157.4 21736.2 5814.7 6037.2 6073.3 6088.1 6089.7 5425.6 5963.3 5966.9 6059.3 6097.4 5859.4 5979.7 5995.7 6001.2 6006.7 5702.3 5819.7 5832.8 5887.0 5988.8 5120.5 5135.6 5148.2 5156.5 5157.3 24760.8 25855.7 25862.9 26054.5 26670.1 2296.7 2482.9 2539.4 2605.4 2641.8 14974.9 15043.0 15170.0 15239.8 15896.8 1740.1 1785.4 1811.5 1854.1 1870.9 121540.0 123238.0 124015.0 124168.1 126094.4 7761.1 9363.9 11217.7 11253.8 11314.7 11615.3 12146.8 12794.4 12886.8 13893.8 8391.2 8452.5 8601.5 8758.0 8806.5 8700.8 8735.8 8745.0 8820.6 8851.4 5692.3 6672.5 6787.3 6797.8 6814.0 1851.6 1882.1 1882.6 1887.3 1888.2 920.6 979.4 1009.1 1043.7 1056.7 18761.8 20920.0 21419.9 21512.0 21571.2 946.3 957.9 1006.3 1012.8 1038.4 8371.7 8542.0 9629.6 10542.3 11359.9 583451.0 585762.0 593907.0 602742.0 614830.0 114739.0 117786.5 118938.0 119979.0 120232.4 975.2 996.8 1054.1 1067.5 1068.3 18236.5 18997.7 19211.3 19231.5 19366.0 48734.8 49298.8 51338.0 53129.7 54662.0 79459.0 81362.0 81600.6 81752.0 83366.6 7757.3 8078.5 8126.0 8243.5 8381.6 5856.2 7368.5 12414.4 12577.1 12646.7 8333.9 11087.7 11262.7 11308.4 11382.5 7999.9 10700.9 10791.1 10956.0 11108.2 11019.6 11259.1 11274.9 11293.9 11598.8 6564.5 8134.8 8246.6 8367.6 8459.2 10943.0 10967.3 11235.7 11294.4 11313.7 17:44:30 INFO - PROCESS | 1991 | 17:44:30 INFO - PROCESS | 1991 | [#3] mootools.html Cycles:180 Average:5662.23 Median:4902.55 stddev:3187.86 (65.0%) stddev-sans-first:3180.38 17:44:30 INFO - PROCESS | 1991 | Values: 1356.8 1571.4 1604.6 1796.0 1928.9 6531.5 8582.8 8761.0 8784.6 8820.9 8514.6 8603.4 8702.9 8703.8 8719.9 1934.5 2098.7 2354.9 2386.2 2393.8 2386.2 2391.3 2395.0 2400.8 2424.4 2366.6 2387.4 2401.9 2403.8 2407.5 2096.3 2303.3 2346.2 2375.8 2381.9 2387.6 2404.0 2404.9 2410.3 2410.6 7429.0 8615.0 8677.3 8681.8 8727.0 5128.5 5165.4 5229.9 5291.4 5339.2 2853.8 3396.0 3521.3 3544.7 3553.9 1010.0 1017.7 1018.2 1021.4 1035.0 11542.8 11544.9 11563.7 11564.4 11569.8 4689.5 4735.9 4744.1 4804.0 4817.8 9756.9 9786.3 9892.5 9897.6 9908.0 5342.9 5343.9 5350.1 5354.2 5372.8 4411.6 4584.9 4760.3 4925.7 4938.5 3924.5 3968.5 4026.1 4045.1 4067.6 1254.0 1283.8 1286.2 1299.0 1299.7 656.3 745.2 750.9 836.4 933.4 4771.6 5332.6 5638.6 5759.3 5842.1 948.4 956.6 966.4 979.9 982.1 3789.0 3909.5 3910.3 3921.0 3923.4 10455.1 10585.7 10809.4 10899.3 10957.8 10751.4 10782.2 10878.7 10883.2 10914.5 7875.9 8187.5 8260.3 8280.7 8326.4 5696.2 5729.3 5907.6 5930.3 5937.7 9690.4 9714.0 10016.3 10121.1 10236.1 9826.5 10051.6 10054.5 10251.6 10273.2 7460.9 7488.2 7515.1 7518.1 7548.1 8186.8 8212.6 8223.7 8276.3 8286.0 7197.4 7400.0 7459.1 7510.9 7522.5 7193.3 7240.0 7269.8 7429.2 7445.1 6812.2 6854.6 6925.3 6942.2 6978.1 4644.3 4773.2 4873.9 4899.2 4904.2 4900.9 4945.8 4965.9 5005.4 5005.4 17:44:30 INFO - PROCESS | 1991 | 17:44:30 INFO - PROCESS | 1991 | [#4] prototype.html Cycles:180 Average:4474.27 Median:5008.31 stddev:1400.66 (28.0%) stddev-sans-first:1369.19 17:44:30 INFO - PROCESS | 1991 | Values: 304.9 312.7 316.1 320.4 320.4 4527.4 4534.5 5367.3 5388.9 5431.8 4556.0 5281.6 5381.0 5387.3 5417.8 3354.6 3679.6 3841.9 3886.0 3964.3 3699.0 3927.7 3945.5 4016.4 4056.9 3383.8 3707.3 4030.0 4051.4 4059.9 3798.8 3828.8 3886.5 4075.9 4086.0 2949.9 3049.9 3146.7 3526.7 3554.2 4250.0 4892.5 4916.4 4933.8 5105.4 3780.9 4347.3 4612.5 4649.2 4692.6 4956.7 5235.4 5353.6 5561.0 5607.8 3915.7 3919.4 3966.3 3966.6 3975.3 5138.5 5382.4 5487.1 5578.4 5623.8 3344.7 3504.3 3578.9 3662.5 3662.8 3405.9 3594.6 4024.9 4052.6 4062.8 3690.5 4091.3 4376.9 4668.1 4687.3 4260.9 5291.7 5483.8 5643.4 5667.6 1219.0 1272.7 1387.0 1388.3 1389.9 780.5 824.9 833.7 876.2 877.3 4501.9 4608.3 5183.4 5245.2 5267.7 4717.8 5600.6 5780.3 5803.9 5817.8 4639.9 4986.7 5031.5 5036.7 5109.7 2696.4 2870.7 2954.4 2992.0 3027.4 5875.0 6012.0 6043.6 6066.8 6085.9 5301.7 5552.7 5886.3 5905.7 5968.4 5966.2 6019.6 6023.4 6042.3 6083.8 5554.5 5682.2 5702.5 5804.4 5836.5 4852.5 5029.9 5593.0 5704.6 5722.3 5174.9 6000.7 6009.3 6067.1 6085.2 3274.1 3834.4 3945.6 3973.1 3988.0 4637.5 4742.6 5222.6 5491.9 5755.4 4824.6 5193.8 5197.7 5615.5 5673.2 4579.0 5273.4 5549.4 5587.5 5623.9 4772.2 5302.9 5413.0 5446.1 5453.3 4808.1 5309.6 5380.0 5466.6 5622.7 5201.9 5454.8 5467.7 5470.3 5481.2 17:44:30 INFO - PROCESS | 1991 | 17:44:30 INFO - PROCESS | 1991 | [#5] yui.html Cycles:180 Average:17932.63 Median:31453.18 stddev:25126.09 (79.9%) stddev-sans-first:25163.16 17:44:30 INFO - PROCESS | 1991 | Values: 675.8 703.8 705.5 713.3 724.2 14777.8 16888.5 17428.8 17439.0 17444.2 14426.0 14433.2 14481.0 14490.6 14505.3 732.1 749.5 927.9 932.6 937.6 902.4 919.6 925.2 936.6 937.7 934.3 948.3 959.6 960.7 961.4 767.3 942.6 957.7 958.8 961.3 726.0 728.6 729.5 730.1 730.6 14664.8 14726.9 14837.3 14929.4 15041.1 12021.8 12073.2 12168.8 12333.3 12361.8 10807.8 10942.3 11041.4 11486.2 11665.2 4024.9 4044.9 4048.1 4147.4 4185.7 81401.6 81973.0 83330.2 83988.0 84132.6 9246.4 9287.1 9325.5 9515.1 9544.6 17405.6 20111.5 20319.0 20529.7 20549.6 7099.5 7486.5 7505.8 7550.4 7716.3 4025.9 4120.3 4147.4 4162.6 4163.8 3450.9 3462.4 3547.7 3555.1 3569.0 616.0 633.1 638.7 640.6 641.7 481.2 501.9 504.4 574.3 587.9 7812.6 8038.7 8128.3 8169.8 8263.3 574.8 598.2 609.2 610.9 625.7 3853.8 3855.2 3857.2 3952.5 4021.8 90376.1 90674.0 91373.2 92397.1 92478.5 69495.7 74062.6 79402.6 80681.2 80805.6 68096.0 68382.7 69104.3 71321.6 71763.6 7610.3 7691.9 7803.6 7874.4 7902.2 39818.8 40199.6 43240.9 43268.4 43840.0 56500.0 62280.7 62579.7 63062.7 63524.0 9987.7 10153.9 10316.7 10734.5 10980.8 11423.4 12099.4 12178.9 12260.8 12379.3 11524.5 11739.0 11770.7 11793.1 11830.1 11344.5 11600.4 11739.0 12354.6 12437.4 11159.9 11276.4 11302.3 11309.4 11525.8 9496.5 9585.3 9665.0 9679.5 9718.4 7889.4 9540.6 9584.1 9762.3 9816.3 17:44:30 INFO - PROCESS | 1991 | -------- Summary: end -------- 17:44:30 INFO - PROCESS | 1991 | 17:44:30 INFO - TEST-INFO | 1991: exit 0 17:44:30 INFO - TEST-OK | dromaeo_css | took 1114442ms 17:44:30 INFO - TEST-START | kraken 17:44:30 INFO - operating with platform_type : mac_ 17:44:30 INFO - Initialising browser for kraken test... 17:44:30 INFO - TEST-INFO | started process 2111 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpKX7CE2/profile http://localhost:49392/getInfo.html) 17:44:32 INFO - PROCESS | 2111 | __metrics Screen width/height:1600/1200 17:44:32 INFO - PROCESS | 2111 | colorDepth:24 17:44:32 INFO - PROCESS | 2111 | Browser inner width/height: 1024/689 17:44:32 INFO - PROCESS | 2111 | __metrics 17:44:32 INFO - PROCESS | 2111 | 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" 17:44:32 ERROR - PROCESS | 2111 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:44:33 INFO - TEST-INFO | 2111: exit 0 17:44:33 INFO - Browser initialized. 17:44:33 INFO - Running cycle 1/1 for kraken test... 17:44:33 INFO - TEST-INFO | started process 2113 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/tmpKX7CE2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:44:45 INFO - PROCESS | 2113 | RSS: Main: 239050752 17:44:45 INFO - PROCESS | 2113 | 17:44:48 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49392/tests/kraken/driver.html?testName=audio-beat-detection) 17:44:49 INFO - PROCESS | 2113 | RSS: Main: 287911936 17:44:49 INFO - PROCESS | 2113 | 17:44:51 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49392/tests/kraken/driver.html?testName=audio-dft) 17:44:52 INFO - PROCESS | 2113 | RSS: Main: 296534016 17:44:52 INFO - PROCESS | 2113 | 17:44:56 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49392/tests/kraken/driver.html?testName=audio-fft) 17:44:56 INFO - PROCESS | 2113 | RSS: Main: 384925696 17:44:56 INFO - PROCESS | 2113 | 17:44:58 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49392/tests/kraken/driver.html?testName=audio-oscillator) 17:44:59 INFO - PROCESS | 2113 | RSS: Main: 285831168 17:44:59 INFO - PROCESS | 2113 | 17:45:01 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49392/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:45:02 INFO - PROCESS | 2113 | RSS: Main: 289296384 17:45:02 INFO - PROCESS | 2113 | 17:45:05 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49392/tests/kraken/driver.html?testName=imaging-darkroom) 17:45:06 INFO - PROCESS | 2113 | RSS: Main: 330674176 17:45:06 INFO - PROCESS | 2113 | 17:45:09 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49392/tests/kraken/driver.html?testName=imaging-desaturate) 17:45:09 INFO - PROCESS | 2113 | RSS: Main: 332701696 17:45:09 INFO - PROCESS | 2113 | 17:45:12 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49392/tests/kraken/driver.html?testName=json-parse-financial) 17:45:13 INFO - PROCESS | 2113 | RSS: Main: 329117696 17:45:13 INFO - PROCESS | 2113 | 17:45:15 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49392/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:45:15 INFO - PROCESS | 2113 | RSS: Main: 316805120 17:45:15 INFO - PROCESS | 2113 | 17:45:17 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49392/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:45:18 INFO - PROCESS | 2113 | RSS: Main: 324042752 17:45:18 INFO - PROCESS | 2113 | 17:45:20 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49392/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:45:21 INFO - PROCESS | 2113 | RSS: Main: 326291456 17:45:21 INFO - PROCESS | 2113 | 17:45:24 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49392/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:45:24 INFO - PROCESS | 2113 | RSS: Main: 331640832 17:45:24 INFO - PROCESS | 2113 | 17:45:27 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49392/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:45:28 INFO - PROCESS | 2113 | RSS: Main: 328802304 17:45:28 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | Cycle 1(1): loaded http://localhost:49392/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49392/tests/kraken/driver.html?testName=ai-astar) 17:45:30 INFO - PROCESS | 2113 | RSS: Main: 375279616 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | __start_tp_report 17:45:30 INFO - PROCESS | 2113 | _x_x_mozilla_page_load 17:45:30 INFO - PROCESS | 2113 | _x_x_mozilla_page_load_details 17:45:30 INFO - PROCESS | 2113 | |i|pagename|runs| 17:45:30 INFO - PROCESS | 2113 | |0;ai-astar;112;105;112;112;111;112;110;112;110;111 17:45:30 INFO - PROCESS | 2113 | |1;audio-beat-detection;147;116;103;247;142;142;144;114;194;138 17:45:30 INFO - PROCESS | 2113 | |2;audio-dft;185;186;185;184;183;183;183;183;181;184 17:45:30 INFO - PROCESS | 2113 | |3;audio-fft;76;57;56;157;79;80;90;47;113;80 17:45:30 INFO - PROCESS | 2113 | |4;audio-oscillator;95;77;138;77;86;75;101;79;77;104 17:45:30 INFO - PROCESS | 2113 | |5;imaging-gaussian-blur;87;84;105;131;87;84;94;130;95;82 17:45:30 INFO - PROCESS | 2113 | |6;imaging-darkroom;93;101;92;93;93;93;93;95;92;92 17:45:30 INFO - PROCESS | 2113 | |7;imaging-desaturate;83;83;84;83;89;85;85;85;86;85 17:45:30 INFO - PROCESS | 2113 | |8;json-parse-financial;56;55;56;55;57;55;59;115;57;55 17:45:30 INFO - PROCESS | 2113 | |9;json-stringify-tinderbox;65;69;78;78;74;71;69;70;69;69 17:45:30 INFO - PROCESS | 2113 | |10;stanford-crypto-aes;85;81;82;84;85;84;82;85;81;85 17:45:30 INFO - PROCESS | 2113 | |11;stanford-crypto-ccm;136;141;130;143;138;143;144;139;217;137 17:45:30 INFO - PROCESS | 2113 | |12;stanford-crypto-pbkdf2;210;227;167;162;165;168;161;162;160;191 17:45:30 INFO - PROCESS | 2113 | |13;stanford-crypto-sha256-iterative;66;67;66;66;67;65;65;65;65;65 17:45:30 INFO - PROCESS | 2113 | __end_tp_report 17:45:30 INFO - PROCESS | 2113 | __start_cc_report 17:45:30 INFO - PROCESS | 2113 | _x_x_mozilla_cycle_collect,2265 17:45:30 INFO - PROCESS | 2113 | __end_cc_report 17:45:30 INFO - PROCESS | 2113 | __startTimestamp1463791530450__endTimestamp 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | ------- Summary: start ------- 17:45:30 INFO - PROCESS | 2113 | Number of tests: 14 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:2.16 (1.9%) stddev-sans-first:2.24 17:45:30 INFO - PROCESS | 2113 | Values: 112.0 105.0 112.0 112.0 111.0 112.0 110.0 112.0 110.0 111.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#1] audio-beat-detection Cycles:10 Average:148.70 Median:143.00 stddev:42.57 (29.8%) stddev-sans-first:45.14 17:45:30 INFO - PROCESS | 2113 | Values: 147.0 116.0 103.0 247.0 142.0 142.0 144.0 114.0 194.0 138.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.42 (0.8%) stddev-sans-first:1.42 17:45:30 INFO - PROCESS | 2113 | Values: 185.0 186.0 185.0 184.0 183.0 183.0 183.0 183.0 181.0 184.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#3] audio-fft Cycles:10 Average:83.50 Median:77.50 stddev:31.98 (41.3%) stddev-sans-first:33.81 17:45:30 INFO - PROCESS | 2113 | Values: 76.0 57.0 56.0 157.0 79.0 80.0 90.0 47.0 113.0 80.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:77.00 stddev:19.74 (25.6%) stddev-sans-first:20.88 17:45:30 INFO - PROCESS | 2113 | Values: 95.0 77.0 138.0 77.0 86.0 75.0 101.0 79.0 77.0 104.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#5] imaging-gaussian-blur Cycles:10 Average:97.90 Median:85.50 stddev:18.49 (21.6%) stddev-sans-first:19.19 17:45:30 INFO - PROCESS | 2113 | Values: 87.0 84.0 105.0 131.0 87.0 84.0 94.0 130.0 95.0 82.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:93.00 stddev:2.71 (2.9%) stddev-sans-first:2.86 17:45:30 INFO - PROCESS | 2113 | Values: 93.0 101.0 92.0 93.0 93.0 93.0 93.0 95.0 92.0 92.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.81 (2.1%) stddev-sans-first:1.80 17:45:30 INFO - PROCESS | 2113 | Values: 83.0 83.0 84.0 83.0 89.0 85.0 85.0 85.0 86.0 85.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:56.00 stddev:18.67 (33.3%) stddev-sans-first:19.67 17:45:30 INFO - PROCESS | 2113 | Values: 56.0 55.0 56.0 55.0 57.0 55.0 59.0 115.0 57.0 55.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:70.50 stddev:4.21 (6.0%) stddev-sans-first:3.82 17:45:30 INFO - PROCESS | 2113 | Values: 65.0 69.0 78.0 78.0 74.0 71.0 69.0 70.0 69.0 69.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:84.50 stddev:1.71 (2.0%) stddev-sans-first:1.72 17:45:30 INFO - PROCESS | 2113 | Values: 85.0 81.0 82.0 84.0 85.0 84.0 82.0 85.0 81.0 85.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:142.00 stddev:25.01 (17.6%) stddev-sans-first:26.22 17:45:30 INFO - PROCESS | 2113 | Values: 136.0 141.0 130.0 143.0 138.0 143.0 144.0 139.0 217.0 137.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:167.50 stddev:23.81 (14.2%) stddev-sans-first:22.12 17:45:30 INFO - PROCESS | 2113 | Values: 210.0 227.0 167.0 162.0 165.0 168.0 161.0 162.0 160.0 191.0 17:45:30 INFO - PROCESS | 2113 | 17:45:30 INFO - PROCESS | 2113 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 17:45:30 INFO - PROCESS | 2113 | Values: 66.0 67.0 66.0 66.0 67.0 65.0 65.0 65.0 65.0 65.0 17:45:30 INFO - PROCESS | 2113 | -------- Summary: end -------- 17:45:30 INFO - PROCESS | 2113 | 17:45:31 INFO - TEST-INFO | 2113: exit 0 17:45:31 INFO - TEST-OK | kraken | took 60713ms 17:45:31 INFO - SUITE-END | took 1175s 17:45:31 INFO - Completed test suite (00:19:35) 17:45:31 INFO - javascript benchmark 17:45:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2979.553067039944, 3395.7622966392346, 3426.725861931046, 3469.5663042119736, 3490.9825450872736, 5432.375276843164, 5558.666480011202, 5662.235598194243, 5760.827175184741, 5781.768729250835, 5665.971670141644, 5729.914051289234, 5791.015527360349, 5797.536197104232, 5825.213596164516, 5155.510226528474, 5186.988537235241, 5233.058049551079, 5251.711155886424, 5334.866628334284, 5292.947070529303, 5335.306410166673, 5344.35867695877, 5375.0, 5375.865603359908, 5137.743112844361, 5196.402413722432, 5212.921806172911, 5251.2123181523075, 5261.500157485033, 5184.274201611777, 5227.973860130713, 5230.8169214077325, 5305.761240744176, 5340.198970154469, 5156.20078887773, 5222.556082732972, 5225.843224703265, 5227.0, 5292.391374991887, 4696.835610753607, 5657.51911087554, 5668.263125793662, 5716.056850619642, 5726.112452569859, 6086.765601141693, 6146.8770624587705, 6152.984757515004, 6222.782202622931, 6263.87472250557, 6264.123022776815, 6293.96853015732, 6298.3701629837105, 6324.462420694201, 6350.26971898233, 5664.971675141598, 5848.67832269225, 5873.911891321633, 5909.23179986599, 5913.822585322448, 6203.193584833943, 6254.9687251563455, 6260.154879091356, 6271.937280627135, 6324.0, 5962.9043225348305, 6170.10533472653, 6184.845378865564, 6198.597091189148, 6200.720967556471, 6129.847850912876, 6287.1198032275515, 6295.716692748746, 6303.432691057735, 6308.810735677937, 5781.4507621775865, 6127.097902969389, 6193.194884664965, 6242.063690446469, 6251.874962500725, 6188.257409110935, 6228.6574238416415, 6274.466670333073, 6300.7479700811455, 6335.809925702236, 4898.632602554822, 4930.852074437773, 4994.700317980933, 5002.299678045077, 5027.371578552681, 4502.077074199781, 4523.23105072132, 4524.660650451229, 4548.385967894358, 4549.976255342588, 5682.744276507485, 5695.971520142372, 5719.571032172604, 5740.827775166752, 5831.708414579339, 6314.494840412756, 6324.0, 6324.683765811691, 6329.3354197809485, 6339.2392912850755, 5736.62711923732, 5743.310802703702, 5757.424257574209, 5769.249997500382, 5773.451522105393, 5286.36563612369, 5383.16560933056, 5383.488568585978, 5420.674759514442, 5440.945590544044, 6360.713767880364, 6406.615603063738, 6407.455366293824, 6411.230652321751, 6425.903611445832, 6297.2128483939505, 6314.084457753508, 6332.683365831782, 6336.619802811846, 6343.460805831556, 6198.969005154945, 6258.311585725475, 6268.529860260407, 6390.7443702251385, 6420.839479013062, 6182.659953702409, 6196.380361963767, 6201.968990155201, 6204.937950620617, 6217.626942383471, 6110.7106460192745, 6249.375062493896, 6259.280182779039, 6271.749130034747, 6291.811245662638, 6006.339302676614, 6016.969915150396, 6056.485198758155, 6119.173911522065, 6130.754769809158, 6071.787487437919, 6091.451769340781, 6116.66358350295, 6130.877382452415, 6151.26184857802, 5777.45114214149, 5860.501857342174, 5916.023856063874, 5935.495482883831, 5942.762289508371, 6060.818175454743, 6076.422739839708, 6112.8777424452155, 6157.0456579230295, 6159.753609855556, 5824.912626310524, 5833.445822646841, 5889.057750759858, 5963.463288304073, 6012.488938440281], "value": 5720.979261530535, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.9754012299384, 501.3282201849522, 501.44590227798307, 502.9287617374991, 504.0, 30564.930227442044, 34359.484607730905, 36767.08082297944, 43383.0, 45501.0, 43772.56227437725, 44293.7785311073, 44362.778186109026, 44398.55601443985, 44528.777356113176, 8666.783330416727, 9549.904500954972, 9664.468454235015, 9747.415155090683, 11701.59044433445, 11564.768704625945, 11770.0, 11877.227980181304, 11883.989860861837, 11889.583864564742, 11848.526058957632, 11865.881341186563, 11877.049836013099, 11896.345700986441, 11903.92864642182, 8695.391322607451, 11650.475728592233, 11913.523459061627, 11931.343776092312, 11932.448781658415, 11727.765444691144, 11736.589219377282, 11744.882551174464, 11887.227330223508, 12016.939915300454, 40344.798276008434, 41215.1756964859, 41256.38115428271, 41637.0, 41641.95895102646, 3112.8910488133033, 3350.6816852398983, 3365.175531994677, 3392.5589673342556, 3768.5289338832645, 29485.557716634266, 29525.0, 29701.108966731033, 30375.544366834514, 30692.53961190584, 2767.8200916940336, 2769.8615069246457, 2830.0236418435607, 2836.773058155343, 2842.1331493894354, 483905.0, 493384.0, 497582.0, 497591.0, 497698.5115074438, 34454.13864653404, 35351.823240883634, 53475.0, 65291.67354163294, 65445.345546543926, 36755.264894701955, 36805.63194368075, 36816.26367472689, 36833.894983150545, 36966.445503317475, 15088.170150641601, 15228.162451065076, 15234.933554651068, 15239.695206096038, 15245.695086098216, 11560.248583842023, 11712.238704484182, 12638.747225055631, 12659.177153484994, 12889.226646401246, 7752.26353496417, 7939.841203175904, 7961.004874390701, 7998.64006119726, 8009.959950200212, 3278.0821370016433, 3281.2125089978713, 3286.4905939578925, 3289.210589458513, 3313.867445302161, 996.476849653923, 1001.3140998416109, 1032.8037672842136, 1081.291753901196, 1092.7213560542011, 30386.84806575953, 31106.377872442423, 32129.357412851612, 32366.676333236366, 33073.50389744156, 1036.5439206749156, 1042.7288904884786, 1060.7242117049623, 1081.9404932728776, 1091.1925175370168, 12940.611781646565, 15190.848091519165, 15246.923765381323, 15400.537983860502, 15505.84494155044, 690146.0, 692121.0, 695267.0, 696548.0, 699333.0, 60506.697466512385, 60898.69550652307, 61128.77742445126, 61325.0, 61574.0, 1322.9536966206138, 1447.4789075932656, 1454.1057249791302, 1477.5567329801017, 1495.588713103905, 24131.155409560582, 30273.697263027087, 30598.847005765718, 30936.53595196029, 31883.0, 52957.20564191463, 53445.46554534561, 53779.46220537901, 53838.92322153457, 53923.73038134784, 583910.0, 586950.0, 589500.0, 589770.0, 590140.0, 21457.678134828304, 22104.115835367047, 25754.0, 25809.0, 25904.740952590986, 45121.0, 45385.092298154515, 46061.0, 46185.0, 47104.0, 39689.60310396859, 39734.0, 40183.0, 40723.79638101909, 41011.0, 37824.054398640255, 38989.0, 39110.022249443995, 39741.0, 40054.399184012844, 37702.62297376991, 38545.61454385532, 38905.61094389133, 39408.62069827542, 40920.0, 28450.6085931964, 31805.840970794998, 34194.658053419145, 34333.14167145841, 34467.655323446445, 20581.0, 25100.62349064729, 25158.49683006293, 25853.482930340913, 25861.870690646425], "value": 19116.504674155767, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.79113278829453, 387.73176915046963, 390.91595307008987, 391.4695587478966, 410.38032570818075, 13292.468301267962, 19605.313814016496, 20084.995750212503, 21182.788172118275, 21186.152553897824, 20557.897210513926, 20803.063862126204, 20943.47641308968, 21157.365279041653, 21736.239231626896, 5814.7092645367775, 6037.154798328237, 6073.331933487335, 6088.086786981944, 6089.725962331683, 5425.6202065855405, 5963.284405871302, 5966.910496342558, 6059.272887253535, 6097.359777223394, 5859.44335288147, 5979.730912108966, 5995.700214989233, 6001.159837622737, 6006.699665016754, 5702.287214098238, 5819.709014549277, 5832.825015249549, 5887.0, 5988.820335389945, 5120.462351453119, 5135.6148288878485, 5148.176291793333, 5156.458571849977, 5157.30376399185, 24760.752392476024, 25855.74144258571, 25862.870685646452, 26054.478910421876, 26670.06654767074, 2296.7014288142436, 2482.8758562071826, 2539.4286285585595, 2605.387733882536, 2641.77544908683, 14974.925125374413, 15043.0, 15170.013949093383, 15239.847601523954, 15896.76154857678, 1740.0951505217342, 1785.4108144312345, 1811.4837271377726, 1854.0729635182408, 1870.9345172918881, 121540.0, 123238.0, 124015.0, 124168.1374779379, 126094.36952815177, 7761.068671759425, 9363.90636093644, 11217.71955701114, 11253.774924501628, 11314.660560183207, 11615.30308181495, 12146.757064858653, 12794.360281985864, 12886.806697899352, 13893.791593125909, 8391.202835730595, 8452.450590711584, 8601.483910965362, 8758.0, 8806.471611703319, 8700.78248043804, 8735.825283494294, 8744.956275218583, 8820.603072861737, 8851.380403371832, 5692.316921969307, 6672.466202703772, 6787.321267873272, 6797.762078327334, 6813.965930170318, 1851.6019055903046, 1882.1436246507806, 1882.5575989642423, 1887.3016983716113, 1888.2258274107824, 920.6179435534178, 979.4025644356936, 1009.1371877045168, 1043.6555936540924, 1056.6671498477954, 18761.780484268478, 20920.0, 21419.92900354976, 21512.031958561904, 21571.2450064247, 946.2619157057507, 957.899420560845, 1006.2603986070233, 1012.8379459286626, 1038.445424256323, 8371.744238364294, 8541.974963004355, 9629.566669499749, 10542.314749541256, 11359.886401135884, 583451.0, 585762.0, 593907.0, 602742.0, 614830.0, 114739.0, 117786.51405822345, 118938.0, 119979.0, 120232.39883800525, 975.2149519636619, 996.785691076422, 1054.1408751867205, 1067.530286673861, 1068.3483075324045, 18236.544086397946, 18997.715034274774, 19211.32760353381, 19231.51921201981, 19366.0, 48734.75632621814, 49298.7815548294, 51338.0, 53129.734351328, 54662.0, 79459.0, 81362.0, 81600.59199703964, 81752.0, 83366.58316708621, 7757.26306000929, 8078.515289082674, 8125.959370203348, 8243.464174828618, 8381.622826972924, 5856.150858125633, 7368.521046131984, 12414.379281035732, 12577.119601627799, 12646.747065058831, 8333.874991875005, 11087.72280692989, 11262.718432039264, 11308.378039207593, 11382.487788049391, 7999.920010798701, 10700.946495267473, 10791.1367090631, 10956.013958743433, 11108.166887483632, 11019.614313498989, 11259.142241530055, 11274.943625281821, 11293.887061129284, 11598.826017609912, 6564.540482166202, 8134.75595732129, 8246.587670616565, 8367.623456944573, 8459.196376344238, 10943.030254553938, 10967.341959482457, 11235.66293011211, 11294.43527823622, 11313.71715707114], "value": 9658.378254503501, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.7829147336429, 1571.4028669105737, 1604.6068713165273, 1795.955101122472, 1928.9228430862765, 6531.510136739746, 8582.785430364236, 8761.0, 8784.604692788824, 8820.8676869847, 8514.574271286427, 8603.397762156652, 8702.869456958135, 8703.825923481527, 8719.912800871989, 1934.5260411199276, 2098.727165468487, 2354.9175778847743, 2386.200622791367, 2393.7965272951733, 2386.188695843413, 2391.330427480308, 2394.988025059881, 2400.8319417640773, 2424.4302588891596, 2366.5858474766933, 2387.415083304584, 2401.927942161738, 2403.843750156243, 2407.4823912858733, 2096.2767845093417, 2303.343547089088, 2346.2257455039794, 2375.7624237576106, 2381.8570885746913, 2387.6418537219383, 2403.9879800600884, 2404.8557086574688, 2410.3251089694913, 2410.6384042393606, 7428.962855185743, 8615.0, 8677.305815534743, 8681.782955426102, 8727.0, 5128.538431541179, 5165.354330708661, 5229.921551176735, 5291.444398338158, 5339.199120131972, 2853.8145020573597, 3396.0, 3521.29574085184, 3544.6987006104255, 3553.9111522211892, 1009.9646512372105, 1017.6692574913183, 1018.241410149436, 1021.4433133942019, 1034.953427095775, 11542.769144617061, 11544.884551154464, 11563.710907227387, 11564.363959982198, 11569.768604627945, 4689.507601701772, 4735.881602959885, 4744.074905393415, 4804.0, 4817.759112044384, 9756.853647195298, 9786.314957952874, 9892.455914924749, 9897.60409583623, 9908.0, 5342.866428339323, 5343.893122137535, 5350.063738845686, 5354.170103633943, 5372.838814835562, 4411.558844115627, 4584.931226031612, 4760.2625041860165, 4925.679830810986, 4938.481459446778, 3924.4898163238595, 3968.464257325282, 4026.0941288210506, 4045.110075783323, 4067.613576710208, 1253.9686507837196, 1283.7560863435917, 1286.202554416249, 1298.9870101298866, 1299.7465494228725, 656.2846497317946, 745.2324106170653, 750.8573371059481, 836.393614629401, 933.3932943586723, 4771.618270538349, 5332.573394128461, 5638.633488823213, 5759.280089988752, 5842.065269556851, 948.4167237149242, 956.5599824081, 966.3815158298695, 979.872616559857, 982.1259079419323, 3788.962110378916, 3909.4722212500947, 3910.3156947534067, 3921.0197450637343, 3923.430153249626, 10455.059044686015, 10585.682429527127, 10809.35143891369, 10899.346039237671, 10957.83563246552, 10751.44104104916, 10782.245242832927, 10878.67363979066, 10883.18376121794, 10914.508847101899, 7875.881861772078, 8187.508749474931, 8260.256576908227, 8280.71017514384, 8326.375521835887, 5696.20253164549, 5729.28383952006, 5907.645541267538, 5930.347661757117, 5937.703114844327, 9690.418574885529, 9713.951430242803, 10016.298859079794, 10121.139703125022, 10236.078752912274, 9826.476896081118, 10051.648192313234, 10054.49727513636, 10251.641192558225, 10273.178145748172, 7460.925390746023, 7488.176300607038, 7515.13575938774, 7518.060242469692, 7548.094228692592, 8186.754397368089, 8212.589370531568, 8223.671053157806, 8276.29651479631, 8286.047104582809, 7197.424206063398, 7400.000999865165, 7459.142198647008, 7510.924890751023, 7522.473426860067, 7193.280671932765, 7240.0, 7269.818254543678, 7429.219931907072, 7445.069366329209, 6812.216595091628, 6854.554453960476, 6925.3074692530345, 6942.166939967236, 6978.127734033246, 4644.256918892961, 4773.212419950669, 4873.853784386474, 4899.167141585867, 4904.215325547895, 4900.8774780630765, 4945.777440015136, 4965.925511117408, 5005.374328208974, 5005.399352077774], "value": 4540.020021755708, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.8780487804878, 312.65451675898134, 316.0518444666002, 320.42003972809215, 320.44722853078457, 4527.366168736371, 4534.455865296165, 5367.275417818593, 5388.892222155555, 5431.755570999305, 4555.9544404555945, 5281.63028587999, 5381.0, 5387.272718183054, 5417.753916599175, 3354.614219364768, 3679.5530971903654, 3841.8847434576937, 3886.0, 3964.3062464068817, 3699.0, 3927.725059245854, 3945.487086678728, 4016.417619445179, 4056.8580099696514, 3383.847726852285, 3707.2956138333766, 4030.0, 4051.412545180947, 4059.918801623966, 3798.8480460781534, 3828.827702753355, 3886.455896174524, 4075.8777236682945, 4085.9591404085877, 2949.9262518436994, 3049.923751906198, 3146.716795488417, 3526.717862570988, 3554.2180720241763, 4249.957500424987, 4892.486288939645, 4916.385451818523, 4933.80264789408, 5105.447276361819, 3780.8865734028022, 4347.282698354799, 4612.469565999886, 4649.209634362132, 4692.577668009862, 4956.653034287601, 5235.424103348629, 5353.57171426285, 5560.972195139039, 5607.831765047055, 3915.725899187058, 3919.431682406064, 3966.3257246268204, 3966.6231707987695, 3975.284448799215, 5138.486151384832, 5382.434844341366, 5487.07952216725, 5578.437251182052, 5623.831285061455, 3344.6822551857535, 3504.2641045380246, 3578.8884558110317, 3662.50556174913, 3662.8278950735507, 3405.9148521287166, 3594.6225646307284, 4024.8931543825684, 4052.615001574846, 4062.756234625873, 3690.538682664667, 4091.345384738487, 4376.934345984812, 4668.089722504079, 4687.296905464208, 4260.913467065879, 5291.735413229323, 5483.80488925972, 5643.379228284885, 5667.603267771216, 1218.9577910886242, 1272.6691060324383, 1386.986130138706, 1388.2642199634008, 1389.861013898602, 780.4653812138703, 824.8721448175543, 833.6957010691054, 876.2157868707471, 877.3025444771422, 4501.897035226325, 4608.331791890158, 5183.377994640668, 5245.186996015624, 5267.683938963674, 4717.787699553528, 5600.579956503197, 5780.306363236438, 5803.850247073278, 5817.825465236049, 4639.884002899887, 4986.7257300848805, 5031.547160755476, 5036.748162591929, 5109.693418394908, 2696.4091186200235, 2870.7129287071543, 2954.3648115655237, 2991.9850400747855, 3027.3945210957454, 5874.970625146847, 6012.0, 6043.607165534227, 6066.787662431793, 6085.878282434326, 5301.68189908599, 5552.7223638817895, 5886.293644762656, 5905.704714764244, 5968.37332080134, 5966.151832068292, 6019.608725432833, 6023.397660233942, 6042.305134909541, 6083.7870674526175, 5554.472325129106, 5682.18093596373, 5702.488840457199, 5804.372267332917, 5836.533077353886, 4852.51474852526, 5029.899402011866, 5592.972035139961, 5704.572157088152, 5722.313322401172, 5174.8965020700125, 6000.729967151401, 6009.278886533469, 6067.089936509382, 6085.2393450818645, 3274.1159886830897, 3834.4087203811223, 3945.6054394560315, 3973.145773658677, 3987.986067036128, 4637.536246375335, 4742.573168414721, 5222.55608273301, 5491.862703432446, 5755.424457554211, 4824.638152138534, 5193.76628051731, 5197.688138711689, 5615.494605485363, 5673.220122140204, 4579.0, 5273.367195936512, 5549.445055494418, 5587.525060369914, 5623.915641265467, 4772.186569103857, 5302.886393857333, 5413.024246150048, 5446.121088224556, 5453.291072160752, 4808.124831315719, 5309.57523398135, 5379.973100134474, 5466.590005749665, 5622.734884650921, 5201.869953251199, 5454.754536045808, 5467.6719396836315, 5470.316210473691, 5481.232627432242], "value": 4032.572592378711, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.8242856857217, 703.771274335841, 705.4567982653357, 713.2546488919079, 724.235931092697, 14777.778333324995, 16888.493345199648, 17428.82571174288, 17439.0, 17444.215010324533, 14426.0, 14433.206173660445, 14480.986330956837, 14490.5652830415, 14505.274736263198, 732.092205664977, 749.4641331448014, 927.8700981862545, 932.5570354081825, 937.5687183895416, 902.3548163063408, 919.6229545886188, 925.1950802801572, 936.5691781780355, 937.6999360204737, 934.2572654739498, 948.3219498058886, 959.5586030426012, 960.7357976556433, 961.4231461123305, 767.3362541401648, 942.6465075596651, 957.7031120352678, 958.7842735384518, 961.293449314753, 726.0343742822034, 728.6247582494966, 729.4930023633577, 730.1092666946317, 730.6492883415937, 14664.780028299583, 14726.852731472656, 14837.332320045516, 14929.40282388714, 15041.097534147986, 12021.759564808743, 12073.215241009306, 12168.756624867541, 12333.260004399765, 12361.814572781326, 10807.783844323149, 10942.288751231225, 11041.447927603587, 11486.185884458278, 11665.24175928562, 4024.9195016099516, 4044.919101617951, 4048.0891799345204, 4147.39862719907, 4185.66514678828, 81401.59299203525, 81973.0, 83330.16669833285, 83988.0, 84132.57933710354, 9246.445213287225, 9287.071292870794, 9325.533723313943, 9515.096065873731, 9544.570494327772, 17405.564860878327, 20111.497212569804, 20318.9840507974, 20529.692054619194, 20549.58900821975, 7099.503034787618, 7486.475946683789, 7505.774826755206, 7550.433717471212, 7716.344110750537, 4025.879223623296, 4120.299549076664, 4147.3986271991, 4162.5629308922735, 4163.770992595436, 3450.9309813803584, 3462.376772181032, 3547.6807087362263, 3555.1112221944513, 3568.9643103568633, 615.9630422174683, 633.1452539072308, 638.7221558622015, 640.5836206465741, 641.6919878458273, 481.17478524330244, 501.89962007598626, 504.39220739009403, 574.2764117212259, 587.9147523609053, 7812.57030863308, 8038.678452861937, 8128.349732021542, 8169.795755106051, 8263.256306932404, 574.7930744931821, 598.2312728144313, 609.1502354215844, 610.8808782287412, 625.6621424430756, 3853.845846166178, 3855.20968201518, 3857.1514266861245, 3952.505936757905, 4021.792966815255, 90376.09623903809, 90674.0, 91373.17253655022, 92397.07602923884, 92478.53760731287, 69495.65252173707, 74062.62968685121, 79402.6029869847, 80681.19318806854, 80805.59597201976, 68096.0, 68382.65808670924, 69104.3089569098, 71321.64339178374, 71763.6411817948, 7610.277023682741, 7691.884676721956, 7803.609819508888, 7874.448788584744, 7902.24928631779, 39818.80090599528, 40199.59800401959, 43240.91897702582, 43268.35097473478, 43840.0, 56500.0, 62280.68859655673, 62579.6871015642, 63062.68468657627, 63524.0, 9987.700368988943, 10153.949230253802, 10316.658834351787, 10734.51694673726, 10980.780384392427, 11423.378145501174, 12099.395030248628, 12178.939105304416, 12260.75478490443, 12379.319137447526, 11524.481398336928, 11739.0, 11770.705732356417, 11793.11551633648, 11830.11274154459, 11344.502420629573, 11600.361980091177, 11739.0, 12354.567590134302, 12437.378131093228, 11159.944200278947, 11276.379799111128, 11302.321860688386, 11309.377984210947, 11525.758096575955, 9496.47769372691, 9585.329026968324, 9664.95167524186, 9679.467629280458, 9718.368306060083, 7889.368850491832, 9540.570674319533, 9584.137427631267, 9762.267829912927, 9816.31285810015], "value": 6871.453008923982, "unit": "score"}], "name": "dromaeo_css", "value": 7143.638975824147, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 112.0, 112.0, 111.0, 112.0, 110.0, 112.0, 110.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 103.0, 247.0, 142.0, 142.0, 144.0, 114.0, 194.0, 138.0], "value": 148.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 185.0, 184.0, 183.0, 183.0, 183.0, 183.0, 181.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 57.0, 56.0, 157.0, 79.0, 80.0, 90.0, 47.0, 113.0, 80.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 77.0, 138.0, 77.0, 86.0, 75.0, 101.0, 79.0, 77.0, 104.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 105.0, 131.0, 87.0, 84.0, 94.0, 130.0, 95.0, 82.0], "value": 97.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 101.0, 92.0, 93.0, 93.0, 93.0, 93.0, 95.0, 92.0, 92.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 83.0, 89.0, 85.0, 85.0, 85.0, 86.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 55.0, 57.0, 55.0, 59.0, 115.0, 57.0, 55.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 78.0, 78.0, 74.0, 71.0, 69.0, 70.0, 69.0, 69.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 82.0, 84.0, 85.0, 84.0, 82.0, 85.0, 81.0, 85.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 141.0, 130.0, 143.0, 138.0, 143.0, 144.0, 139.0, 217.0, 137.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 227.0, 167.0, 162.0, 165.0, 168.0, 161.0, 162.0, 160.0, 191.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 67.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1500.3, "alertThreshold": 2.0}]} 17:45:31 INFO - Return code: 0 17:45:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:45:31 ERROR - # TBPL SUCCESS # 17:45:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:45:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:45:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:45:31 INFO - 2016-05-20 17:45:31.772 screenresolution[2123:11540] starting screenresolution argv=screenresolution get 17:45:31 INFO - 2016-05-20 17:45:31.774 screenresolution[2123:11540] Display 0: 1600x1200x32@60 17:45:31 INFO - 2016-05-20 17:45:31.780 screenresolution[2124:11543] starting screenresolution argv=screenresolution list 17:45:31 INFO - Available Modes on Display 0 17:45:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:45:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:45:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:45:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:45:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:45:32 INFO - Intel Iris: 17:45:32 INFO - Chipset Model: Intel Iris 17:45:32 INFO - Type: GPU 17:45:32 INFO - Bus: Built-In 17:45:32 INFO - VRAM (Dynamic, Max): 1536 MB 17:45:32 INFO - Vendor: Intel (0x8086) 17:45:32 INFO - Device ID: 0x0a2e 17:45:32 INFO - Revision ID: 0x0009 17:45:32 INFO - Displays: 17:45:32 INFO - Display: 17:45:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:45:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:45:32 INFO - Display Serial Number: 1600x1200 60 17:45:32 INFO - Main Display: Yes 17:45:32 INFO - Mirror: Off 17:45:32 INFO - Online: Yes 17:45:32 INFO - Rotation: Supported 17:45:32 INFO - Return code: 0 17:45:32 INFO - Running post-action listener: _resource_record_post_action 17:45:32 INFO - ##### 17:45:32 INFO - ##### Finished run-tests step (success) 17:45:32 INFO - ##### 17:45:32 INFO - Running post-run listener: _resource_record_post_run 17:45:32 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32869888; Write bytes: 701241856; Read time: 378; Write time: 2100 17:45:32 INFO - TinderboxPrint: CPU usage
53.8% 17:45:32 INFO - TinderboxPrint: I/O read bytes / time
32,869,888 / 378 17:45:32 INFO - TinderboxPrint: I/O write bytes / time
701,241,856 / 2,100 17:45:32 INFO - TinderboxPrint: CPU idle
2,239.8 (46.2%) 17:45:32 INFO - TinderboxPrint: CPU system
969.0 (20.0%) 17:45:32 INFO - TinderboxPrint: CPU user
1,636.7 (33.8%) 17:45:32 INFO - TinderboxPrint: Swap in / out
425,807,872 / 0 17:45:32 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 377661952; Write bytes: 371955200; Read time: 28135; Write time: 706 17:45:32 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31948288; Write bytes: 302228480; Read time: 365; Write time: 1312 17:45:32 INFO - Running post-run listener: _upload_blobber_files 17:45:32 INFO - Blob upload gear active. 17:45:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:45:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:45:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:45:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:45:32 INFO - (blobuploader) - INFO - Open directory for files ... 17:45:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:45:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:45:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:33 INFO - (blobuploader) - INFO - Done attempting. 17:45:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:45:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:45:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:35 INFO - (blobuploader) - INFO - Done attempting. 17:45:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:45:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:45:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:37 INFO - (blobuploader) - INFO - Done attempting. 17:45:37 INFO - (blobuploader) - INFO - Iteration through files over. 17:45:37 INFO - Return code: 0 17:45:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:45:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:45:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1677ef5bda86ac947fe5687cc5dac565c0387cac9267361e75fb7a0e5afdfd1e98907a8cd7fd8dc4533f6804135571d258555461c7f13640aa43ab2029957667", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b60e392a780ea5d5f90e97a510b578562cb198055c2282147a9245fcb34b9657055a75fed30bea3163da00f0c3e459389350d11ed4c8279ac5520a336cac284", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92ef69b4e9476e0905a79ba7af93d903f0d4acedd01bc90eb3275af41b59bf33ab6f605a9fd5161e7d99b0a6f2c18c91d60f170872a7e9bda18124be13d700b8"} 17:45:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:45:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:45:37 INFO - Contents: 17:45:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1677ef5bda86ac947fe5687cc5dac565c0387cac9267361e75fb7a0e5afdfd1e98907a8cd7fd8dc4533f6804135571d258555461c7f13640aa43ab2029957667", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b60e392a780ea5d5f90e97a510b578562cb198055c2282147a9245fcb34b9657055a75fed30bea3163da00f0c3e459389350d11ed4c8279ac5520a336cac284", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92ef69b4e9476e0905a79ba7af93d903f0d4acedd01bc90eb3275af41b59bf33ab6f605a9fd5161e7d99b0a6f2c18c91d60f170872a7e9bda18124be13d700b8"} 17:45:37 INFO - Running post-run listener: copy_logs_to_upload_dir 17:45:37 INFO - Copying logs to upload dir... 17:45:37 INFO - mkdir: /builds/slave/test/build/upload/logs 17:45:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.273557 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-05-20 17:45:38.068122) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 17:45:38.071524) ========= 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.wILTwZ9RKa/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.5H2QghSkHo/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1677ef5bda86ac947fe5687cc5dac565c0387cac9267361e75fb7a0e5afdfd1e98907a8cd7fd8dc4533f6804135571d258555461c7f13640aa43ab2029957667", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b60e392a780ea5d5f90e97a510b578562cb198055c2282147a9245fcb34b9657055a75fed30bea3163da00f0c3e459389350d11ed4c8279ac5520a336cac284", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92ef69b4e9476e0905a79ba7af93d903f0d4acedd01bc90eb3275af41b59bf33ab6f605a9fd5161e7d99b0a6f2c18c91d60f170872a7e9bda18124be13d700b8"} build_url:https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011107 build_url: 'https://queue.taskcluster.net/v1/task/XEC0mkToTWmJAY2LSAG4Gg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1677ef5bda86ac947fe5687cc5dac565c0387cac9267361e75fb7a0e5afdfd1e98907a8cd7fd8dc4533f6804135571d258555461c7f13640aa43ab2029957667", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b60e392a780ea5d5f90e97a510b578562cb198055c2282147a9245fcb34b9657055a75fed30bea3163da00f0c3e459389350d11ed4c8279ac5520a336cac284", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/92ef69b4e9476e0905a79ba7af93d903f0d4acedd01bc90eb3275af41b59bf33ab6f605a9fd5161e7d99b0a6f2c18c91d60f170872a7e9bda18124be13d700b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 17:45:38.101031) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 17:45:38.101395) ========= 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.wILTwZ9RKa/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.5H2QghSkHo/Listeners TMPDIR=/var/folders/vs/0sk_gyqd48x911v5vww2j48c00000w/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.005178 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 17:45:38.150276) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 17:45:38.150596) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 17:45:38.151029) ========= ========= Total master_lag: 0.18 =========