builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0173 starttime: 1463102002.89 results: success (0) buildid: 20160512154437 builduid: 053295922f2c45ab8d382c23fc6a3787 revision: be6dbfceb79e570e6859200c2c5a8673f58c277e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:22.892184) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:22.892653) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:22.893009) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:22.912035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:22.912364) ========= 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.g6lYxcuJFN/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.k7WhYznUHF/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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-12 18:13:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.4M=0.001s 2016-05-12 18:13:23 (13.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:23.056678) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:23.057006) ========= 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.g6lYxcuJFN/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.k7WhYznUHF/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.026430 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:23.105422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 18:13:23.105789) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev be6dbfceb79e570e6859200c2c5a8673f58c277e --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 be6dbfceb79e570e6859200c2c5a8673f58c277e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g6lYxcuJFN/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.k7WhYznUHF/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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-12 18:13:23,182 truncating revision to first 12 chars 2016-05-12 18:13:23,182 Setting DEBUG logging. 2016-05-12 18:13:23,182 attempt 1/10 2016-05-12 18:13:23,182 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/be6dbfceb79e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 18:13:24,216 unpacking tar archive at: fx-team-be6dbfceb79e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.278518 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 18:13:24.401072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:24.401419) ========= script_repo_revision: be6dbfceb79e570e6859200c2c5a8673f58c277e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:24.401829) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:24.402107) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 18:13:24.415233) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-12 18:13:24.415534) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.g6lYxcuJFN/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.k7WhYznUHF/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:13:24 INFO - MultiFileLogger online at 20160512 18:13:24 in /builds/slave/test 18:13:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:13:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:13:24 INFO - {'append_to_log': False, 18:13:24 INFO - 'base_work_dir': '/builds/slave/test', 18:13:24 INFO - 'blob_upload_branch': 'Fx-Team', 18:13:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:13:24 INFO - 'branch': 'Fx-Team', 18:13:24 INFO - 'buildbot_json_path': 'buildprops.json', 18:13:24 INFO - 'config_files': ('talos/mac_config.py',), 18:13:24 INFO - 'default_actions': ('clobber', 18:13:24 INFO - 'read-buildbot-config', 18:13:24 INFO - 'download-and-extract', 18:13:24 INFO - 'populate-webroot', 18:13:24 INFO - 'create-virtualenv', 18:13:24 INFO - 'install', 18:13:24 INFO - 'run-tests'), 18:13:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:13:24 INFO - 'download_minidump_stackwalk': True, 18:13:24 INFO - 'download_symbols': 'ondemand', 18:13:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:13:24 INFO - 'tooltool.py': '/tools/tooltool.py', 18:13:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:13:24 INFO - '/tools/misc-python/virtualenv.py')}, 18:13:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:13:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:13:24 INFO - 'installer_path': 'installer.exe', 18:13:24 INFO - 'log_level': 'info', 18:13:24 INFO - 'log_name': 'talos', 18:13:24 INFO - 'log_to_console': True, 18:13:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:13:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:13:24 INFO - 'opt_config_files': (), 18:13:24 INFO - 'pip_index': False, 18:13:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:13:24 INFO - 'cmd': ('bash', 18:13:24 INFO - '-c', 18:13:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:13:24 INFO - 'enabled': True, 18:13:24 INFO - 'halt_on_failure': False, 18:13:24 INFO - 'name': 'check_screen_resolution'},), 18:13:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:13:24 INFO - 'cmd': ('bash', 18:13:24 INFO - '-c', 18:13:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:13:24 INFO - 'enabled': True, 18:13:24 INFO - 'halt_on_failure': False, 18:13:24 INFO - 'name': 'check_screen_resolution'},), 18:13:24 INFO - 'run_cmd_checks_enabled': True, 18:13:24 INFO - 'sps_profile': False, 18:13:24 INFO - 'sps_profile_interval': 0, 18:13:24 INFO - 'suite': 'dromaeojs-e10s', 18:13:24 INFO - 'system_bits': '32', 18:13:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:13:24 INFO - 'title': 't-yosemite-r7-0173', 18:13:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:13:24 INFO - 'use_talos_json': True, 18:13:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:13:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:13:24 INFO - 'work_dir': 'build'} 18:13:24 INFO - ##### 18:13:24 INFO - ##### Running clobber step. 18:13:24 INFO - ##### 18:13:24 INFO - Running pre-action listener: _resource_record_pre_action 18:13:24 INFO - Running main action method: clobber 18:13:24 INFO - rmtree: /builds/slave/test/build 18:13:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:13:26 INFO - Running post-action listener: _resource_record_post_action 18:13:26 INFO - ##### 18:13:26 INFO - ##### Finished clobber step (success) 18:13:26 INFO - ##### 18:13:26 INFO - ##### 18:13:26 INFO - ##### Running read-buildbot-config step. 18:13:26 INFO - ##### 18:13:26 INFO - Running pre-action listener: _resource_record_pre_action 18:13:26 INFO - Running main action method: read_buildbot_config 18:13:26 INFO - Using buildbot properties: 18:13:26 INFO - { 18:13:26 INFO - "project": "", 18:13:26 INFO - "product": "firefox", 18:13:26 INFO - "script_repo_revision": "production", 18:13:26 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 18:13:26 INFO - "repository": "", 18:13:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 18:13:26 INFO - "buildid": "20160512154437", 18:13:26 INFO - "pgo_build": "False", 18:13:26 INFO - "basedir": "/builds/slave/test", 18:13:26 INFO - "buildnumber": 664, 18:13:26 INFO - "slavename": "t-yosemite-r7-0173", 18:13:26 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:13:26 INFO - "platform": "yosemite_r7", 18:13:26 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:13:26 INFO - "branch": "fx-team", 18:13:26 INFO - "repo_path": "integration/fx-team", 18:13:26 INFO - "revision": "be6dbfceb79e570e6859200c2c5a8673f58c277e", 18:13:26 INFO - "stage_platform": "macosx64", 18:13:26 INFO - "builduid": "053295922f2c45ab8d382c23fc6a3787", 18:13:26 INFO - "slavebuilddir": "test" 18:13:26 INFO - } 18:13:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:13:26 INFO - Running post-action listener: _resource_record_post_action 18:13:26 INFO - ##### 18:13:26 INFO - ##### Finished read-buildbot-config step (success) 18:13:26 INFO - ##### 18:13:26 INFO - ##### 18:13:26 INFO - ##### Running download-and-extract step. 18:13:26 INFO - ##### 18:13:26 INFO - Running pre-action listener: _resource_record_pre_action 18:13:26 INFO - Running main action method: download_and_extract 18:13:26 INFO - mkdir: /builds/slave/test/build/tests 18:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:26 INFO - https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:13:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:13:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:13:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/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 18:13:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/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 18:13:27 INFO - Downloaded 1199 bytes. 18:13:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:13:27 INFO - Using the following test package requirements: 18:13:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:13:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:13:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:13:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:13:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:13:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:13:27 INFO - u'jsshell-mac.zip'], 18:13:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:13:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:13:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:13:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:13:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:13:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:13:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:13:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:13:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:13:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:13:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:13:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:27 INFO - https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:13:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:13:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:13:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/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 18:13:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/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 18:13:30 INFO - Downloaded 18151171 bytes. 18:13:30 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'] 18:13:30 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 18:13:33 INFO - Return code: 0 18:13:33 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 18:13:33 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')]} 18:13:33 INFO - https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:13:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:13:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:13:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/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 18:13:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/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 18:13:34 INFO - Downloaded 18151171 bytes. 18:13:34 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'] 18:13:34 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 18:13:37 INFO - Return code: 0 18:13:37 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')]} 18:13:37 INFO - https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:13:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:13:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:13:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/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 18:13:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/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 18:13:38 INFO - Downloaded 10840239 bytes. 18:13:38 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'] 18:13:38 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 18:13:39 INFO - Return code: 0 18:13:39 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')]} 18:13:39 INFO - https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:13:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:13:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:13:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:13:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HUCqfd7MRLS9gmy6j8HhqA/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 18:13:42 INFO - Downloaded 109817660 bytes. 18:13:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:13:42 INFO - mkdir: /builds/slave/test/properties 18:13:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:13:42 INFO - Writing to file /builds/slave/test/properties/build_url 18:13:42 INFO - Contents: 18:13:42 INFO - build_url:https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:13:43 INFO - Running post-action listener: _resource_record_post_action 18:13:43 INFO - Running post-action listener: set_extra_try_arguments 18:13:43 INFO - ##### 18:13:43 INFO - ##### Finished download-and-extract step (success) 18:13:43 INFO - ##### 18:13:43 INFO - ##### 18:13:43 INFO - ##### Running populate-webroot step. 18:13:43 INFO - ##### 18:13:43 INFO - Running pre-action listener: _resource_record_pre_action 18:13:43 INFO - Running main action method: populate_webroot 18:13:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:13:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:13:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:13:43 INFO - '--tppagecycles', 18:13:43 INFO - '7'], 18:13:43 INFO - 'tests': ['tsvgm']}}, 18:13:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:13:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:13:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:13:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:13:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:43 INFO - 'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:13:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:43 INFO - 'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['damp', 'tps']}, 18:13:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:43 INFO - 'tests': ['damp', 'tps']}, 18:13:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['dromaeo_dom']}, 18:13:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:13:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['a11yr', 18:13:43 INFO - 'ts_paint', 18:13:43 INFO - 'tpaint', 18:13:43 INFO - 'sessionrestore', 18:13:43 INFO - 'sessionrestore_no_auto_restore', 18:13:43 INFO - 'tabpaint']}, 18:13:43 INFO - 'other-e10s': {'tests': ['a11yr', 18:13:43 INFO - 'ts_paint', 18:13:43 INFO - 'tpaint', 18:13:43 INFO - 'sessionrestore', 18:13:43 INFO - 'sessionrestore_no_auto_restore', 18:13:43 INFO - 'tabpaint']}, 18:13:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:13:43 INFO - 'ts_paint', 18:13:43 INFO - 'tpaint', 18:13:43 INFO - 'sessionrestore', 18:13:43 INFO - 'sessionrestore_no_auto_restore', 18:13:43 INFO - 'tabpaint']}, 18:13:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:13:43 INFO - 'ts_paint', 18:13:43 INFO - 'tpaint', 18:13:43 INFO - 'sessionrestore', 18:13:43 INFO - 'sessionrestore_no_auto_restore', 18:13:43 INFO - 'tabpaint']}, 18:13:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['a11yr', 18:13:43 INFO - 'ts_paint', 18:13:43 INFO - 'tpaint', 18:13:43 INFO - 'sessionrestore', 18:13:43 INFO - 'sessionrestore_no_auto_restore', 18:13:43 INFO - 'tabpaint']}, 18:13:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['a11yr', 18:13:43 INFO - 'ts_paint', 18:13:43 INFO - 'tpaint', 18:13:43 INFO - 'sessionrestore', 18:13:43 INFO - 'sessionrestore_no_auto_restore', 18:13:43 INFO - 'tabpaint']}, 18:13:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['tsvgx', 18:13:43 INFO - 'tsvgr_opacity', 18:13:43 INFO - 'tart', 18:13:43 INFO - 'tscrollx', 18:13:43 INFO - 'cart']}, 18:13:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:13:43 INFO - 'tsvgr_opacity', 18:13:43 INFO - 'tart', 18:13:43 INFO - 'tscrollx', 18:13:43 INFO - 'cart']}, 18:13:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:43 INFO - 'talos_options': ['--disable-e10s'], 18:13:43 INFO - 'tests': ['tp5o']}, 18:13:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:43 INFO - 'tests': ['tp5o']}, 18:13:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:43 INFO - 'talos_options': ['--disable-e10s', 18:13:43 INFO - '--xperf_path', 18:13:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:43 INFO - 'tests': ['tp5n']}, 18:13:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:13:43 INFO - 'talos_options': ['--xperf_path', 18:13:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:13:43 INFO - 'tests': ['tp5n']}}, 18:13:43 INFO - 'talos.zip': {'path': '', 18:13:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:13:43 INFO - Running post-action listener: _resource_record_post_action 18:13:43 INFO - ##### 18:13:43 INFO - ##### Finished populate-webroot step (success) 18:13:43 INFO - ##### 18:13:43 INFO - ##### 18:13:43 INFO - ##### Running create-virtualenv step. 18:13:43 INFO - ##### 18:13:43 INFO - Running pre-action listener: _resource_record_pre_action 18:13:43 INFO - Running main action method: create_virtualenv 18:13:43 INFO - Creating virtualenv /builds/slave/test/build/venv 18:13:43 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 18:13:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:13:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:13:43 INFO - Using real prefix '/tools/python27' 18:13:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:13:44 INFO - Installing distribute.............................................................................................................................................................................................done. 18:13:47 INFO - Installing pip.................done. 18:13:47 INFO - Return code: 0 18:13:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:47 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 0x108648a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf8dbf0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, '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.g6lYxcuJFN/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.k7WhYznUHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:13:47 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 18:13:47 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 18:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:47 INFO - 'HOME': '/Users/cltbld', 18:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:47 INFO - 'LOGNAME': 'cltbld', 18:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:47 INFO - 'MOZ_NO_REMOTE': '1', 18:13:47 INFO - 'NO_EM_RESTART': '1', 18:13:47 INFO - 'PAGER': '/bin/cat', 18:13:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:47 INFO - 'PWD': '/builds/slave/test', 18:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:47 INFO - 'SHELL': '/bin/bash', 18:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:13:47 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:47 INFO - 'USER': 'cltbld', 18:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:47 INFO - 'XPC_FLAGS': '0x0', 18:13:47 INFO - 'XPC_SERVICE_NAME': '0', 18:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:47 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 18:13:47 INFO - Cleaning up... 18:13:47 INFO - Return code: 0 18:13:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108648a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf8dbf0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, '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.g6lYxcuJFN/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.k7WhYznUHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:13:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 18:13:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:47 INFO - 'HOME': '/Users/cltbld', 18:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:47 INFO - 'LOGNAME': 'cltbld', 18:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:47 INFO - 'MOZ_NO_REMOTE': '1', 18:13:47 INFO - 'NO_EM_RESTART': '1', 18:13:47 INFO - 'PAGER': '/bin/cat', 18:13:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:47 INFO - 'PWD': '/builds/slave/test', 18:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:47 INFO - 'SHELL': '/bin/bash', 18:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:13:47 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:47 INFO - 'USER': 'cltbld', 18:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:47 INFO - 'XPC_FLAGS': '0x0', 18:13:47 INFO - 'XPC_SERVICE_NAME': '0', 18:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:47 INFO - Downloading/unpacking psutil>=0.7.1 18:13:47 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 18:13:48 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 18:13:48 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 18:13:48 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 18:13:48 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 18:13:48 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 18:13:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:13:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:13:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:13:50 INFO - Installing collected packages: psutil 18:13:50 INFO - Running setup.py install for psutil 18:13:50 INFO - building 'psutil._psutil_osx' extension 18:13:50 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 18:13:51 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 18:13:51 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 18:13:51 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 18:13:51 INFO - building 'psutil._psutil_posix' extension 18:13:51 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 18:13:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:13:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:13:51 INFO - ^ 18:13:51 INFO - 1 warning generated. 18:13:51 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 18:13:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:13:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:13:51 INFO - Successfully installed psutil 18:13:51 INFO - Cleaning up... 18:13:51 INFO - Return code: 0 18:13:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108648a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf8dbf0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, '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.g6lYxcuJFN/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.k7WhYznUHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:13:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:13:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:51 INFO - 'HOME': '/Users/cltbld', 18:13:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:51 INFO - 'LOGNAME': 'cltbld', 18:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:51 INFO - 'MOZ_NO_REMOTE': '1', 18:13:51 INFO - 'NO_EM_RESTART': '1', 18:13:51 INFO - 'PAGER': '/bin/cat', 18:13:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:51 INFO - 'PWD': '/builds/slave/test', 18:13:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:51 INFO - 'SHELL': '/bin/bash', 18:13:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:13:51 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:13:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:51 INFO - 'USER': 'cltbld', 18:13:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:51 INFO - 'XPC_FLAGS': '0x0', 18:13:51 INFO - 'XPC_SERVICE_NAME': '0', 18:13:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:13:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:13:54 INFO - Installing collected packages: mozsystemmonitor 18:13:54 INFO - Running setup.py install for mozsystemmonitor 18:13:54 INFO - Successfully installed mozsystemmonitor 18:13:54 INFO - Cleaning up... 18:13:54 INFO - Return code: 0 18:13:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:13: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')]} 18:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13: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')]} 18:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:54 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 0x108648a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf8dbf0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, '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.g6lYxcuJFN/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.k7WhYznUHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:13:54 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 18:13:54 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 18:13:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:13:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:54 INFO - 'HOME': '/Users/cltbld', 18:13:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:54 INFO - 'LOGNAME': 'cltbld', 18:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:54 INFO - 'MOZ_NO_REMOTE': '1', 18:13:54 INFO - 'NO_EM_RESTART': '1', 18:13:54 INFO - 'PAGER': '/bin/cat', 18:13:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:54 INFO - 'PWD': '/builds/slave/test', 18:13:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:54 INFO - 'SHELL': '/bin/bash', 18:13:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:13:54 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:13:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:54 INFO - 'USER': 'cltbld', 18:13:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:54 INFO - 'XPC_FLAGS': '0x0', 18:13:54 INFO - 'XPC_SERVICE_NAME': '0', 18:13:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:55 INFO - Downloading/unpacking blobuploader==1.2.4 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13:57 INFO - Downloading blobuploader-1.2.4.tar.gz 18:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:13:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:13:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:13:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13: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 18:13:58 INFO - Downloading docopt-0.6.1.tar.gz 18:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:13:58 INFO - Installing collected packages: blobuploader, requests, docopt 18:13:58 INFO - Running setup.py install for blobuploader 18:13:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:13:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:13:58 INFO - Running setup.py install for requests 18:13:59 INFO - Running setup.py install for docopt 18:13:59 INFO - Successfully installed blobuploader requests docopt 18:13:59 INFO - Cleaning up... 18:13:59 INFO - Return code: 0 18:13:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:13:59 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')]} 18:13:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:13:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:59 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')]} 18:13:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:13:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:13:59 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 0x108648a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf8dbf0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, '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.g6lYxcuJFN/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.k7WhYznUHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:13:59 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 18:13:59 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 18:13:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:13:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:13:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:13:59 INFO - 'HOME': '/Users/cltbld', 18:13:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:13:59 INFO - 'LOGNAME': 'cltbld', 18:13:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:13:59 INFO - 'MOZ_NO_REMOTE': '1', 18:13:59 INFO - 'NO_EM_RESTART': '1', 18:13:59 INFO - 'PAGER': '/bin/cat', 18:13:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:13:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:13:59 INFO - 'PWD': '/builds/slave/test', 18:13:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:13:59 INFO - 'SHELL': '/bin/bash', 18:13:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:13:59 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:13:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:13:59 INFO - 'USER': 'cltbld', 18:13:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:13:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:13:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:13:59 INFO - 'XPC_FLAGS': '0x0', 18:13:59 INFO - 'XPC_SERVICE_NAME': '0', 18:13:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:13:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:13:59 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-_a_PBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-mNbYEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-suv71j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-X6Wl3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-7B2thP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-hSa9Hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-4tCeON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-JmQ7aO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-FxQybd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-PkdhWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:00 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-4omIgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:01 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-QfjMGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:01 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-gnGDmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:01 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-MAzE2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:01 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-1rZwGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:01 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-f413aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:01 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-XZjHkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:14:01 INFO - Running setup.py install for manifestparser 18:14:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:14:01 INFO - Running setup.py install for mozcrash 18:14:01 INFO - Running setup.py install for mozdebug 18:14:02 INFO - Running setup.py install for mozdevice 18:14:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:14:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:14:02 INFO - Running setup.py install for mozfile 18:14:02 INFO - Running setup.py install for mozhttpd 18:14:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:14:02 INFO - Running setup.py install for mozinfo 18:14:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:14:02 INFO - Running setup.py install for mozInstall 18:14:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:14:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:14:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:14:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:14:02 INFO - Running setup.py install for mozleak 18:14:03 INFO - Running setup.py install for mozlog 18:14:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:14:03 INFO - Running setup.py install for moznetwork 18:14:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:14:03 INFO - Running setup.py install for mozprocess 18:14:03 INFO - Running setup.py install for mozprofile 18:14:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:14:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:14:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:14:03 INFO - Running setup.py install for mozrunner 18:14:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:14:03 INFO - Running setup.py install for mozscreenshot 18:14:03 INFO - Running setup.py install for moztest 18:14:04 INFO - Running setup.py install for mozversion 18:14:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:14:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:14:04 INFO - Cleaning up... 18:14:04 INFO - Return code: 0 18:14:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:04 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 0x108648a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf8dbf0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, '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.g6lYxcuJFN/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.k7WhYznUHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:14:04 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 18:14:04 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 18:14:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:14:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:04 INFO - 'HOME': '/Users/cltbld', 18:14:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:04 INFO - 'LOGNAME': 'cltbld', 18:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:04 INFO - 'MOZ_NO_REMOTE': '1', 18:14:04 INFO - 'NO_EM_RESTART': '1', 18:14:04 INFO - 'PAGER': '/bin/cat', 18:14:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:04 INFO - 'PWD': '/builds/slave/test', 18:14:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:04 INFO - 'SHELL': '/bin/bash', 18:14:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:14:04 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:14:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:04 INFO - 'USER': 'cltbld', 18:14:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:04 INFO - 'XPC_FLAGS': '0x0', 18:14:04 INFO - 'XPC_SERVICE_NAME': '0', 18:14:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:04 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-3z_Yc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:04 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)) 18:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:04 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-VqeIlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:04 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)) 18:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:04 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-QuIjb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:04 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)) 18:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:04 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-FIEWig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:04 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)) 18:14:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:04 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-qDjkEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:05 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)) 18:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:05 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-3vwsCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:05 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)) 18:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:05 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-xYclYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:05 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)) 18:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:05 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-w_duN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:05 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)) 18:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:05 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-qztHql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:05 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)) 18:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:05 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-DS8umZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:05 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)) 18:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:05 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-8JqNkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:05 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)) 18:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:05 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-bEoKSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:05 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)) 18:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:05 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-VkknEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:05 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)) 18:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:05 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-bYI8TS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:06 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)) 18:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:06 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Wszqjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:06 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)) 18:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:06 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-bghH3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:06 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)) 18:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:06 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-z4Hc9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:06 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)) 18:14:06 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)) 18:14:06 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)) 18:14:06 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)) 18:14:06 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)) 18:14:06 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)) 18:14:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14:06 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 18:14:06 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 18:14:06 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 18:14:06 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 18:14:06 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 18:14:06 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 18:14:08 INFO - Downloading blessings-1.6.tar.gz 18:14:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:14:09 INFO - Installing collected packages: blessings 18:14:09 INFO - Running setup.py install for blessings 18:14:09 INFO - Successfully installed blessings 18:14:09 INFO - Cleaning up... 18:14:09 INFO - Return code: 0 18:14:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:14:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:09 INFO - Reading from file tmpfile_stdout 18:14:09 INFO - Current package versions: 18:14:09 INFO - blessings == 1.6 18:14:09 INFO - blobuploader == 1.2.4 18:14:09 INFO - docopt == 0.6.1 18:14:09 INFO - manifestparser == 1.1 18:14:09 INFO - mozInstall == 1.12 18:14:09 INFO - mozcrash == 0.17 18:14:09 INFO - mozdebug == 0.1 18:14:09 INFO - mozdevice == 0.48 18:14:09 INFO - mozfile == 1.2 18:14:09 INFO - mozhttpd == 0.7 18:14:09 INFO - mozinfo == 0.9 18:14:09 INFO - mozleak == 0.1 18:14:09 INFO - mozlog == 3.2 18:14:09 INFO - moznetwork == 0.27 18:14:09 INFO - mozprocess == 0.22 18:14:09 INFO - mozprofile == 0.28 18:14:09 INFO - mozrunner == 6.11 18:14:09 INFO - mozscreenshot == 0.1 18:14:09 INFO - mozsystemmonitor == 0.0 18:14:09 INFO - moztest == 0.7 18:14:09 INFO - mozversion == 1.4 18:14:09 INFO - psutil == 3.1.1 18:14:09 INFO - requests == 1.2.3 18:14:09 INFO - wsgiref == 0.1.2 18:14:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:09 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 0x108648a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf8dbf0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, '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.g6lYxcuJFN/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.k7WhYznUHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:14:09 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 18:14:09 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 18:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:09 INFO - 'HOME': '/Users/cltbld', 18:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:09 INFO - 'LOGNAME': 'cltbld', 18:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:09 INFO - 'MOZ_NO_REMOTE': '1', 18:14:09 INFO - 'NO_EM_RESTART': '1', 18:14:09 INFO - 'PAGER': '/bin/cat', 18:14:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:09 INFO - 'PWD': '/builds/slave/test', 18:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:09 INFO - 'SHELL': '/bin/bash', 18:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:14:09 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:09 INFO - 'USER': 'cltbld', 18:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:09 INFO - 'XPC_FLAGS': '0x0', 18:14:09 INFO - 'XPC_SERVICE_NAME': '0', 18:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:09 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)) 18:14:09 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)) 18:14:09 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)) 18:14:09 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)) 18:14:09 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)) 18:14:09 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)) 18:14:09 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)) 18:14:09 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)) 18:14:09 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)) 18:14:09 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)) 18:14:09 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)) 18:14:09 INFO - Cleaning up... 18:14:09 INFO - Return code: 0 18:14:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108648a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf8dbf0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, '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.g6lYxcuJFN/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.k7WhYznUHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:14:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:09 INFO - 'HOME': '/Users/cltbld', 18:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:09 INFO - 'LOGNAME': 'cltbld', 18:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:09 INFO - 'MOZ_NO_REMOTE': '1', 18:14:09 INFO - 'NO_EM_RESTART': '1', 18:14:09 INFO - 'PAGER': '/bin/cat', 18:14:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:09 INFO - 'PWD': '/builds/slave/test', 18:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:09 INFO - 'SHELL': '/bin/bash', 18:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:14:09 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:09 INFO - 'USER': 'cltbld', 18:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:09 INFO - 'XPC_FLAGS': '0x0', 18:14:09 INFO - 'XPC_SERVICE_NAME': '0', 18:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:10 INFO - Downloading/unpacking jsonschema 18:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:14:12 INFO - Downloading/unpacking functools32 (from jsonschema) 18:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:12 INFO - Downloading functools32-3.2.3-2.tar.gz 18:14:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:14:13 INFO - warning: no files found matching '*.txt' 18:14:13 INFO - no previously-included directories found matching 'build' 18:14:13 INFO - no previously-included directories found matching 'dist' 18:14:13 INFO - no previously-included directories found matching '.git*' 18:14:13 INFO - Installing collected packages: jsonschema, functools32 18:14:13 INFO - Running setup.py install for functools32 18:14:13 INFO - warning: no files found matching '*.txt' 18:14:13 INFO - no previously-included directories found matching 'build' 18:14:13 INFO - no previously-included directories found matching 'dist' 18:14:13 INFO - no previously-included directories found matching '.git*' 18:14:13 INFO - Successfully installed jsonschema functools32 18:14:13 INFO - Cleaning up... 18:14:13 INFO - Return code: 0 18:14:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:13 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 0x108648a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10864e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf8dbf0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, '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.g6lYxcuJFN/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.k7WhYznUHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 18:14:13 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 18:14:13 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 18:14:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:14:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:13 INFO - 'HOME': '/Users/cltbld', 18:14:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:13 INFO - 'LOGNAME': 'cltbld', 18:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:13 INFO - 'MOZ_NO_REMOTE': '1', 18:14:13 INFO - 'NO_EM_RESTART': '1', 18:14:13 INFO - 'PAGER': '/bin/cat', 18:14:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:13 INFO - 'PWD': '/builds/slave/test', 18:14:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:13 INFO - 'SHELL': '/bin/bash', 18:14:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:14:13 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:14:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:13 INFO - 'USER': 'cltbld', 18:14:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:13 INFO - 'XPC_FLAGS': '0x0', 18:14:13 INFO - 'XPC_SERVICE_NAME': '0', 18:14:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:13 INFO - Downloading/unpacking flake8 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:14:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:14:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:14:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:14:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:14:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:14:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:14:17 INFO - no previously-included directories found matching 'docs/_build' 18:14:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:14:17 INFO - Running setup.py install for pep8 18:14:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:14:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:14:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:14:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:14:17 INFO - no previously-included directories found matching 'docs/_build' 18:14:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:14:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:14:17 INFO - Cleaning up... 18:14:17 INFO - Return code: 0 18:14:17 INFO - Running post-action listener: _resource_record_post_action 18:14:17 INFO - Running post-action listener: _start_resource_monitoring 18:14:17 INFO - Starting resource monitoring. 18:14:17 INFO - ##### 18:14:17 INFO - ##### Finished create-virtualenv step (success) 18:14:17 INFO - ##### 18:14:17 INFO - ##### 18:14:17 INFO - ##### Running install step. 18:14:17 INFO - ##### 18:14:17 INFO - Running pre-action listener: _resource_record_pre_action 18:14:17 INFO - Running main action method: install 18:14:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:17 INFO - Reading from file tmpfile_stdout 18:14:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:14:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:14:17 INFO - Reading from file tmpfile_stdout 18:14:17 INFO - Output received: 18:14:17 INFO - Usage: mozinstall [options] installer 18:14:17 INFO - Options: 18:14:17 INFO - -h, --help show this help message and exit 18:14:17 INFO - -d DEST, --destination=DEST 18:14:17 INFO - Directory to install application into. [default: 18:14:17 INFO - "/builds/slave/test"] 18:14:17 INFO - --app=APP Application being installed. [default: firefox] 18:14:17 INFO - mkdir: /builds/slave/test/build/application 18:14:17 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'] 18:14:17 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 18:14:51 INFO - Reading from file tmpfile_stdout 18:14:51 INFO - Output received: 18:14:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:14:51 INFO - Running post-action listener: _resource_record_post_action 18:14:51 INFO - ##### 18:14:51 INFO - ##### Finished install step (success) 18:14:51 INFO - ##### 18:14:51 INFO - ##### 18:14:51 INFO - ##### Running run-tests step. 18:14:51 INFO - ##### 18:14:51 INFO - Running pre-action listener: _resource_record_pre_action 18:14:51 INFO - Running main action method: run_tests 18:14:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:14:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:14:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:14:51 INFO - 2016-05-12 18:14:51.197 screenresolution[1645:7640] starting screenresolution argv=screenresolution get 18:14:51 INFO - 2016-05-12 18:14:51.200 screenresolution[1645:7640] Display 0: 1600x1200x32@60 18:14:51 INFO - 2016-05-12 18:14:51.206 screenresolution[1646:7642] starting screenresolution argv=screenresolution list 18:14:51 INFO - Available Modes on Display 0 18:14:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:14:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:14:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:14:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:14:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:14:51 INFO - Intel Iris: 18:14:51 INFO - Chipset Model: Intel Iris 18:14:51 INFO - Type: GPU 18:14:51 INFO - Bus: Built-In 18:14:51 INFO - VRAM (Dynamic, Max): 1536 MB 18:14:51 INFO - Vendor: Intel (0x8086) 18:14:51 INFO - Device ID: 0x0a2e 18:14:51 INFO - Revision ID: 0x0009 18:14:51 INFO - Displays: 18:14:51 INFO - Display: 18:14:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:14:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:14:51 INFO - Display Serial Number: 1600x1200 60 18:14:51 INFO - Main Display: Yes 18:14:51 INFO - Mirror: Off 18:14:51 INFO - Online: Yes 18:14:51 INFO - Rotation: Supported 18:14:51 INFO - Return code: 0 18:14:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:14:51 INFO - Python 2.7.3 18:14:51 INFO - Return code: 0 18:14:51 INFO - grabbing minidump binary from tooltool 18:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:14:51 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 0x1084ecd30>, '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 0x108310db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084e3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:14:51 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 18:14:51 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 18:14:51 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 18:14:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:14:51 INFO - Return code: 0 18:14:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:14:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:14:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:14:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:14:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:14:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:14:52 INFO - Return code: 0 18:14:52 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:14:52 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0173 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:14:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g6lYxcuJFN/Render', 18:14:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:52 INFO - 'HOME': '/Users/cltbld', 18:14:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:52 INFO - 'LOGNAME': 'cltbld', 18:14:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:14:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:14:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:52 INFO - 'MOZ_NO_REMOTE': '1', 18:14:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:14:52 INFO - 'NO_EM_RESTART': '1', 18:14:52 INFO - 'PAGER': '/bin/cat', 18:14:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:52 INFO - 'PWD': '/builds/slave/test', 18:14:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:14:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:52 INFO - 'SHELL': '/bin/bash', 18:14:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k7WhYznUHF/Listeners', 18:14:52 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 18:14:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:52 INFO - 'USER': 'cltbld', 18:14:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:52 INFO - 'XPC_FLAGS': '0x0', 18:14:52 INFO - 'XPC_SERVICE_NAME': '0', 18:14:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:14:52 INFO - mozversion application_buildid: 20160512154437 18:14:52 INFO - mozversion application_changeset: be6dbfceb79e570e6859200c2c5a8673f58c277e 18:14:52 INFO - mozversion application_display_name: Nightly 18:14:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:14:52 INFO - mozversion application_name: Firefox 18:14:52 INFO - mozversion application_remotingname: firefox 18:14:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:14:52 INFO - mozversion application_vendor: Mozilla 18:14:52 INFO - mozversion application_version: 49.0a1 18:14:52 INFO - mozversion platform_buildid: 20160512154437 18:14:52 INFO - mozversion platform_changeset: be6dbfceb79e570e6859200c2c5a8673f58c277e 18:14:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:14:52 INFO - mozversion platform_version: 49.0a1 18:14:52 INFO - using testdate: 1463102092 18:14:52 INFO - actual date: 1463102092 18:14:52 INFO - starting webserver on 'localhost:49273' 18:14:52 INFO - SUITE-START | Running 2 tests 18:14:52 INFO - TEST-START | dromaeo_css 18:14:52 INFO - operating with platform_type : mac_ 18:14:52 INFO - Initialising browser for dromaeo_css test... 18:14:52 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpJEvy6s/profile http://localhost:49273/getInfo.html) 18:14:55 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 18:14:55 INFO - PROCESS | 1664 | colorDepth:24 18:14:55 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 18:14:55 INFO - PROCESS | 1664 | __metrics 18:14:55 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 18:14:56 INFO - TEST-INFO | 1664: exit 0 18:14:56 INFO - Browser initialized. 18:14:56 INFO - Running cycle 1/1 for dromaeo_css test... 18:14:56 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpJEvy6s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:08 INFO - PROCESS | 1667 | RSS: Main: 249614336 18:15:08 INFO - PROCESS | 1667 | 18:15:58 INFO - PROCESS | 1667 | 1463102158254 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] 18:18:09 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 18:18:10 INFO - PROCESS | 1667 | RSS: Main: 262107136 18:18:10 INFO - PROCESS | 1667 | 18:21:12 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 18:21:13 INFO - PROCESS | 1667 | RSS: Main: 273317888 18:21:13 INFO - PROCESS | 1667 | 18:24:15 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 18:24:15 INFO - PROCESS | 1667 | RSS: Main: 275304448 18:24:15 INFO - PROCESS | 1667 | 18:27:17 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 18:27:18 INFO - PROCESS | 1667 | RSS: Main: 276922368 18:27:18 INFO - PROCESS | 1667 | 18:30:21 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 18:30:21 INFO - PROCESS | 1667 | RSS: Main: 279777280 18:30:21 INFO - PROCESS | 1667 | 18:33:24 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 18:33:24 INFO - PROCESS | 1667 | RSS: Main: 280915968 18:33:24 INFO - PROCESS | 1667 | 18:33:24 INFO - PROCESS | 1667 | __start_tp_report 18:33:24 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 18:33:24 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 18:33:24 INFO - PROCESS | 1667 | |i|pagename|runs| 18:33:24 INFO - PROCESS | 1667 | |0;dojo.html;3502.2645244498663;3512.5784905811306;3540.911477213068;3560.6083330833603;3561.7506774525777;5927.2590926134235;5939.940600593993;5977.641341519512;5993.88012239755;6041.486473649745;5860.120981852724;5938.228030356058;5991.520678345732;6012.60918040327;6078.969605151968;5246.7638956247065;5306.204069389584;5398;5398.109311963522;5499.835004949858;383.6796953721282;5331.786728530854;5370.650907690988;5399.244105825188;5416.160495123262;5354.116570765838;5385.946140538584;5386.757595908193;5400.135978243482;5420.783168673248;5397.676139431607;5416.350037995427;5420.864478388033;5426.131818908955;5495.807646732345;5218.008578370098;5257.86855328616;5332.280142180794;5375.758090885881;5401.162819762943;5686.516646085088;5812.825615231508;5890.882182356372;5912.793052243151;5917.20117784098;6207.596506227126;6223;6337.556371054028;6344.333844946306;6359.936400635981;6264.248290205159;6326.177596912418;6332.525060620472;6412.93587064128;6423.454006409461;5066.822661206877;6107.938920610826;6134.17188679527;6138.109974053827;6139.969300153515;6179.258488981351;6251.6874156292915;6498.512611554058;6527.706253218616;6577.112089867806;5466.398696143421;6220.409061139185;6268;6292.811215663537;6310.968445157837;6325.96837015812;6412.422881940648;6433.388828061326;6450.354964503512;6456.192975878015;6050.54620903434;6203.47270482005;6205.689715514206;6225.221847269091;6279;6259.374062593704;6270.561060725706;6291.653959032297;6317.989121740499;6318.873622527523;4991.725455099931;5072.239164125337;5073.644844860824;5074.4671809460215;5076.568491678175;4406.427164468599;4441.444819397575;4501.594856462869;4519.209138400766;4569.908601827945;4992.400911890596;6057.7879774207695;6069.969650151721;6124.960759790135;6155.569110162335;6402.455791257796;6426.389492998158;6426.453751431087;6454.870902582016;6457.289698133295;5906.084556893688;6094.847628809315;6119.776756597957;6127.908081378694;6170.907436388457;5591.636543624732;5595.38450770415;5596.860078497989;5616.6068375213345;5629.605927585029;6443.710033048618;6663.233728121231;6685.465162786868;6693.497987650946;6701.32986701326;6288.5598008138995;6334.588251763621;6340.365963403623;6424.325445828121;6464.256610489854;5182.559482444034;6579.276279609144;6632.104665870239;6672.6997285121315;6724.8991265132045;6213.099100630293;6240.095186198067;6340.207474065742;6352.968235158794;6363.14097596837;6259.6870156492905;6325.557210995186;6388.520860935356;6389.456896163692;6416.967915160394;5445.428230035789;6044.0933859919605;6238.251409830849;6249.625022498482;6310.116583678197;6158.230221222348;6197.070439434121;6228.34602366745;6282.403171698681;6299.181106456132;5169.741512924264;6148.692565371803;6218.782342617987;6232.906506402498;6259.3427690091885;6217.533684973736;6279.560430769802;6290.370962903856;6301.495880329471;6322.557420980487;5081.517255860687;6113.969430152821;6146.985747351637;6156.876862462816;6227.097070924781 18:33:24 INFO - PROCESS | 1667 | |1;ext.html;508.3010860067407;526.5629527492181;539.0889396919207;554.3790954131373;556.866776110615;39350.40974385383;42162;42234.577654223445;42333.941651458656;42341.78829105854;30939.69060309396;34258.14354641135;39035.609643903554;40501.797491012505;42684.57315426845;9608;9682.951585242065;9745.366551174151;9834.950825245864;9849.064338887794;8345.123762004992;8601.569921503931;9829.115379615869;9843;9866.556004979757;9863.950680246624;9883;9894.50527473627;9899.208063354914;9908.653197138101;9816.705498835046;9907.603695852193;9918.752031199278;9922.206223502102;9939.8509022364;8273.710420135327;9608.231341492734;9647.662331818352;9704.660336888175;9722.562484688206;40115.79652610426;40272.798636006635;40330.19339613221;40392.798036009925;40532.39201411981;2835.31952331441;3060.1890499017777;3086.8732912487003;3113.5641010258582;3430.8627654893776;23217;25016;27844.443111137665;28505.144845654664;28603.856980715173;2663.1078588672817;2668.8131830771854;2669.586214136812;2736.1107640017076;2796.426732519849;406627;408573;410010;410063;412448.999999997;35529.46705799415;35604.109897252776;35782.64217357793;35881;35923;36194.457083143774;36968.26063478769;37788.05529861776;37846;37875.81062094727;13064.608061757972;14933;15472;15488.07071575709;15536.766948495782;12631.11582189238;12995.805062924064;13003.869961300456;13129.409176587076;13151.934240328737;8327.8334433311;8385.245327920517;8388.203120703523;8423.452475589189;8444.071152173256;3205.7275131613605;3263.494158405451;3457.8098204598987;3468.583769947572;3476.756627036134;884.5135175653365;979.5298256836759;1023.9078482936422;1072.6942821295893;1091.939943303126;25314.240572782845;28483;29283.85358073196;29745.958891439128;29960.965822747163;914.7530166855045;1087.5269257872692;1117.2402766118955;1123.3596849795538;1180.392098069495;15121.395144194328;15245.466408675644;15286.847131528542;15464.458743943907;15664.216789160497;600259;658529.7073514601;668229;679138.0215494554;686008;60770;61406.69296653488;61547.384526154165;62282;62363.06455403173;1470.7793830925448;1474.5797447727346;1480.7926890235376;1483.9183844888419;1494.4769330734373;25542.72286385636;27911.604419779334;28785.848566057124;29150.56274155932;29572;53099.46900530945;53140.4685953151;53341;53710;53892;611593.9420303048;615344;615656;616302;617641;25620.35949101213;27509;27762.722372776014;28157.296067597654;28520.57219141673;46559.836004100165;46962.29556556586;47291;47546;47936.5206347932;38086.42870356891;38415.58506265437;38668.806655967666;38741.61258387493;39484;34514.65485345215;37490.81254593709;37792.62207378001;38308;38507.80746096364;33019.83490082534;38107.80946095363;38714.8385548434;38884.80557597307;39971.2005759889;33017.834910825295;33251.6699332024;33404.832975834965;34045;34121.658783412844;23614.29157125289;25319;25596.744032559436;25657.97368105255;25767.613485797352 18:33:24 INFO - PROCESS | 1667 | |2;jquery.html;394.7473616885192;399.8020979615087;400.77957123582024;400.8977710683776;420.4093248985175;14485.710285794277;16845;20538.38384848455;20860.269890553835;21326.786732132692;11824.7635047299;12455.314957677325;12475.625731228056;20978.951052447355;21281.893590532025;5431.891362172754;5529.198266251391;5719.914201286984;5732.914006289909;5736.053551164053;5532;5549.361823390302;5558.249636299089;5644.266245388094;5648.43515648436;5387.811426600069;5410.161424979135;5663.292088488939;5669.801556945507;5675.914861277084;4662.906741865144;4823.517648235166;5589.664620122806;5607.607467477278;5692.402297758737;4638.165130276549;4991;5360.276362691065;5375.6774593524515;5389.649672771257;12088.274703517816;13531.59405217845;13906.48039387193;13918;25389.61915571268;1859.33064096925;2124.5155159288956;2174.7607763146043;2186.420598541388;2234.1256223173777;9919;9978.750531236705;10085;11848.0521558276;15047.548573542812;1688.6116193275493;1710.8973461592345;1724.2758041622549;1746.4858813285234;1786;126779;135401;135628;137328.999999999;138658;11547.538098475967;11692.941535292439;11882.643520694393;11966.820497692368;12098.637040888787;12644.747105058032;15485.535433937;15529.534113976599;15539.84460155384;15555.688886222211;8634.352423568258;8703.129687031373;8718.912810871938;8750.562471876381;8762.737117886474;8485.151484851465;8607.526586037828;8608.966923969163;8644.610992505352;8657.783555411039;4649.325847752125;4696.131215725101;4734.337192793012;4745.905081898342;4791.425028996473;1979.8416126709828;2035.2062695548748;2041.2345370486069;2043.2542122125317;2051.0975170924744;857.1471385970999;862.5299211929513;867.5271976772674;977.2768151567789;1020.1736593359254;19790;20549.58900821975;20621.38135855927;20955.58088838245;20996.055177516744;824.6701319472139;919.5907821019582;928.1553786054793;1042.296449896317;1050.112654806687;10804.783904322027;11109.666709998713;11112.61105861307;11118.166137539716;11139.777204455866;506846;609262;621150;632436;635052;103027.4848625767;133055;134872;136589.97510273862;139236.43054277715;861.2851333393394;1001.4241810958582;1012.5544760305442;1012.9898701012895;1034.5861655337926;16622.50132496027;16685.74971375406;18220.81779182191;18751.437456876316;18886.055697214808;51185;52770.47229527656;54563;54929;56141.71929140328;77455.2254477448;77474.6126269365;78427;80246.5987670058;81618.18381816344;7850.371970242252;8091.1099779025435;8124.634391452517;8305.127961563949;8579.65681372738;9124.95437522808;10954.28797128216;11157.27477713946;12558.434870431036;12905.290209038592;10663.680089597325;10766.138708903425;10882.40146791934;10890.891091088988;11049.116070714163;10437.68686939393;10584;10608.363498190134;10740.194485413469;10855.728606784893;9556.805399325085;9729.951350243487;10533.473326333806;10809.405482698527;11197.384143872165;6864.5881247125335;8135.389845761473;8167.060788009455;8173.6321865515;8324.458910171063;10505.527251273557;10684.145268378667;11018.944905275743;11158.609448669582;11174.553017879194 18:33:24 INFO - PROCESS | 1667 | |3;mootools.html;1647.176411794103;1667.341400146942;1946.9221231150755;1980.6533856575086;1992.6712092504738;8326.791830204247;8896.421732587385;8946.597403116859;9429.622815087389;9454.432734035954;8055.1944805519415;9122.631605259197;9224.861627075583;9363.859542106873;9400.435973841557;2466.038245084414;2471.8393304435153;2475.121331927167;2500.174942269056;2502.399424138212;2447.3269850791;2504.198656429943;2506.1228570000535;2508.08454913956;2525.936851578716;2470.17249221511;2472.938176545592;2503.173952595639;2507.887145078476;2512.057978258153;2067.9586408271825;2211.3365990203038;2401.00358351284;2460.8523488590654;2478.491909158618;2088.9346433318965;2380.0479808076734;2409.7831195192343;2422.576049191402;2470.666460027909;9261.861072083924;9321.813563728756;9341.953290233574;9349.065093490664;9376.67181648639;5516.724163791834;5571.637843540157;5694.5159661428825;5699.458551437607;5703.201551782712;3324.0858763839897;3385.6106547747077;3624.474451204587;3681.7238707097076;3696.759710618828;1024.7079582319004;1043.5512729526301;1045.4041196517987;1045.9372437653765;1045.9790804183874;12150.878491215062;12461.563845265373;12586.056045796602;12589.181703189355;12614.55849045279;3997.360422332471;4998.275250088716;5004.424491183488;5063.797448102108;5160.174372100521;10594.15246780271;10607.57569697219;10631.94684026575;10632.255742098134;10644.946775266075;4428.225060614444;5492.780288788484;5580.944190558123;5602.635828671123;5629.802956896489;4103.753774773523;4269.23153832307;4515.503294637587;5077.339945807022;5079.619028572872;3467.2372078142766;4150.439690641785;4239.915201696011;4273.97863010683;4307.396964424983;1366.7266546690503;1375.1542801177404;1375.4841934274648;1391.9721605568034;1404.4803422733653;879.8944126704708;907.1240000793966;920.921721653667;926.8702381666573;960.9711708648751;5503.9174412383845;5995.190649262381;6062.969685151546;6252.218472690914;6275;446.64268585131765;467.0868452175988;470.36970459583637;487.6903166489305;949.7245798718433;4035.3745169498775;4047.2310261050307;4091.386292056156;4159.417681524589;4159.9376009359885;11011.174161937886;11376.488058037408;11457.369844658624;11501.194916355775;11523.193376463567;10641.467926603793;10971.23201375896;11309;11428.542858285742;11454.885451145548;8208.835823283502;8523.06246312905;8606.139386061344;8610.741677749802;8625.91374086251;5650.180723794944;5935.495482884004;6173.1666225060835;6334.968325158345;6360.713767880364;10281.280310378203;10425.791484170426;10491.422971736627;10605.787884242118;10775.730606734895;10693.198010149426;10740.462976851282;10781.838272426077;10795.190360723134;10848.837267440837;7624.5425274483705;7794.181610930771;7855.37157027425;7933.642986065525;8070.919290807252;8788.033316335326;8792.98880628736;8797.560121994004;8858;8895.421797583136;7635.580043097683;7851.764447066597;7871.370290376871;7986.241307075818;7996.5202087872585;7573.2805383488485;7628;7671.578063206354;7789.376849851885;7792.1428642848105;6106.32830388648;7103.82240443993;7425.331720145213;7465.514741541782;7465.776026719207;4983.601311894967;5038.168702164102;5163.4578369270685;5185.42960274377;5190.480951904779;5045.697258164522;5143.871403214949;5190.714510701948;5235.764390602356;5398.325209348832 18:33:24 INFO - PROCESS | 1667 | |4;prototype.html;323.1887961217344;324.2331885091757;330.5950210991535;332.5643407136653;334.9799012059275;4934.3223304076655;5527.364353099395;5583.748731307091;5626.690532020737;5628.268325117734;4659.277811939146;4962.305277261186;4971.353724015873;5353.0899747042995;5543.837903217738;3250.959692898268;3363;4113.300738874398;4199.853005144821;4204.642605378546;3971.5035620547433;4061.939070913939;4169.583041695836;4176.035015139255;4191.517975432834;4029.468801855291;4168.312228482297;4195.85314513992;4211.736479056286;4224.134052519242;3870.5161854768153;3918.3534716771696;3986.342253528179;4162.959260184954;4204.516480604739;3341.7159541438764;3509.2279698466295;3512.227309991798;3631.8547258109643;3680.9325295664225;4587;4850.550472011647;4896.314515967767;5224.63427560071;5290.576753859682;4094.344904815215;4176.770277634729;4589.928129417013;4820.590249828733;4870.6103511718975;4775.492866245214;5527.429745168516;5684.442001099522;5711.257536520267;5789.507891829199;3243.399971005348;3591.4018261873475;4054.837806487737;4070.450489183952;4175.392473897541;4646.79089440976;5669.31968163823;5992.161097446361;6038.637681739066;6102.267727872683;3921.843126274974;3937.960620393759;3943.783091929914;3951.091249012743;3956.7625942443547;4001.7398869073418;4027.2146931348693;4031.234065527599;4040.676745860324;4049.9392509112386;4159.896002599899;4691.061787642485;4725.133572238947;4725.976370118196;4740.217864052462;4863.781129849165;5316.846677095782;5625.915611265835;5833.854153646108;5838.241028666246;1229.936105268936;1329.5080820096625;1397.860213978594;1439.359485029173;1450.6518435575385;857.3955361470148;864.4381152251087;890.2077151335317;916.5234078279391;948.1419315519466;4726.740029298421;4917.188663870421;5201.29782479368;5362;5371.597130215249;5207.453217412117;5829.329627092854;5913.733881975321;5924.7926322578505;5944.494717948936;4258.190943720745;4604.128135648299;4688.624910007191;4879.878003049881;5305.469453054741;2579.5038877451034;2769.418422131335;3053.8167709937475;3122.7970181938554;3122.8126312421327;5137.121473287644;5884.264466941632;6150.661713605795;6169.81490555284;6200.534959878028;5172.603397082767;5290.92063619046;5754.798582049608;6015.398460154037;6102.9694851525455;5718.683933007139;6172.654391331265;6183.350748171467;6185.969070154801;6204.5967012144965;5295.7352132394;5527.5577953764405;5821.825345239649;5857.912131318119;5984.551158663205;5591.412901645432;5962.303371909271;5975.55183361241;5983.3545774912245;6022.45797878193;5265.447128051499;5672.517835983987;5805.390434004369;6366.9044964326495;6389.137466441971;3733.2160246347485;4046.838126474908;4047;4064.552899181147;4064.8700500613772;6052.852516691208;6068.662235770221;6090.589270008809;6091.7563297467605;6108.908366374597;5170.741462926914;5175.689458632343;5784.94215057844;5810.622309549865;5904.940950590439;4991.401031876053;5250.474952504872;5286.784039670821;5472.644278121909;5689.496621486698;5177.197534382177;5392.568594512508;5642.85892852682;5706.861226325587;5777.537796976148;4691.873950251983;4920.507949205194;5107.336046313955;5759.481646651821;5804.883902322014;5442.836714898559;5701.861801824316;5748.54011679073;5754.395788442153;5779.266220133859 18:33:24 INFO - PROCESS | 1667 | |5;yui.html;658.3186402073853;724.8224185074657;738.3207449146785;740.1636151149203;759.0398146344871;13541;13654.453821847126;13689.726205475898;14178.291085445711;14958.326875290611;14328.140311581312;14361.856381436182;14367.209803460806;14394.928025359859;14396.280185990685;730.9305615966475;896.2113340260562;905.425055090016;918.2883265469248;933.9066093390641;913.7624217703378;917.1791246834113;917.8118485710413;918.7978644698155;922.9907700922972;965.8068386322728;972.5040229482944;977.1889331854544;980.6567701304558;983.095552092077;931.6320053578867;954.4464210757744;968.6900191938582;971.8882328532168;975.6146322202761;712.2983860897012;724.9383802376805;727.323589062172;728.1444302944072;732.6117157906318;14406.639834004129;14494.130352178903;14553.199574023425;14593.9784215105;14618.70762584753;12316.876831231752;12437.440315185748;12452;12466.065045121652;12733.61799146027;9771.560279787427;10012.799744005079;10042.548085336177;10144.746381340525;10310.639127630497;3389.5254664347012;3396.733718382802;3416.8974930752115;3424.9315013699834;3474.7567670263093;58253.70873145649;79560;79764.60117699433;80447.5977620114;82407.9999999994;9346.859797103049;9375.718728438158;9422.434653920649;9696.854547181656;9749.31754777173;21087.367378978655;21103.472413189793;21119;21121.471963201042;21207.15171393127;7290.7083716651805;7364.594947277951;7388.44586656003;7426.591537465383;7506.009973576067;4033.3546632538655;4111.671066314688;4113.259613269581;4116.259073366823;4122.649574786176;3418.7265018798435;3499.61504234534;3504.351694936445;3508.2281897982402;3522.295540891832;584.0596639410545;588.3763210996358;597.1401182297478;615.1940957345892;624.0265186309372;537.8278950735727;578.1356871477169;578.5140481995144;591.6568390333596;596.3053043204704;8016.55908925003;8032.1164671886045;8193.426460147852;8272.917270827335;8360.790980225544;534.1347017831139;567.9403662615448;586.1237450012173;589.9351071382144;612.1827360473728;3710.8144592770254;3857.4599556062176;3890.6109389060866;3903.3383554039942;4105.2405305018665;73053.63473182707;82175;94352.0564794357;98423;98482;69110;88438;88611;89237.10762892418;90478;72364.63817680877;77642.44715105667;78769;79894.60052699699;80242.69739105373;7251.311125443074;7527.247275272429;7696.191899850659;7698.499597526143;7758.205890085496;36748.81625591855;37611.62388376191;47179.57434894894;47268;48019.75990120027;67351;67620.6618966902;67824;67978.32021679918;70304;11059.50232239535;11174.106071514097;11200.61829315407;11224.326540407275;11615.76768464643;12012.159148859497;12090.75818483643;12144.574939877062;12169.39153042362;12597.181183223061;11346.489407976496;11383.658490245305;11784.764304713686;11891.345975971404;12044.277343359427;11389.544418223179;11479.827802583135;11623.825642615198;11696.473658685209;11756.23584467007;8653.913460865311;10785.946070269598;10794.622188223375;10978.505967231335;11085.833712494481;9560.808783824423;9582.329236953625;9714.854277185706;9862.55618497155;9989.650362237287;8200.179982001991;8666.913330866862;9546.045395460398;9619.326647134634;9640.951795240979 18:33:24 INFO - PROCESS | 1667 | __end_tp_report 18:33:24 INFO - PROCESS | 1667 | __start_cc_report 18:33:24 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,423 18:33:24 INFO - PROCESS | 1667 | __end_cc_report 18:33:24 INFO - PROCESS | 1667 | __startTimestamp1463103204821__endTimestamp 18:33:24 INFO - PROCESS | 1667 | 18:33:24 INFO - PROCESS | 1667 | ------- Summary: start ------- 18:33:24 INFO - PROCESS | 1667 | Number of tests: 6 18:33:24 INFO - PROCESS | 1667 | 18:33:24 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5857.56 Median:6148.69 stddev:786.19 (12.8%) stddev-sans-first:766.58 18:33:24 INFO - PROCESS | 1667 | Values: 3502.3 3512.6 3540.9 3560.6 3561.8 5927.3 5939.9 5977.6 5993.9 6041.5 5860.1 5938.2 5991.5 6012.6 6079.0 5246.8 5306.2 5398.0 5398.1 5499.8 383.7 5331.8 5370.7 5399.2 5416.2 5354.1 5385.9 5386.8 5400.1 5420.8 5397.7 5416.4 5420.9 5426.1 5495.8 5218.0 5257.9 5332.3 5375.8 5401.2 5686.5 5812.8 5890.9 5912.8 5917.2 6207.6 6223.0 6337.6 6344.3 6359.9 6264.2 6326.2 6332.5 6412.9 6423.5 5066.8 6107.9 6134.2 6138.1 6140.0 6179.3 6251.7 6498.5 6527.7 6577.1 5466.4 6220.4 6268.0 6292.8 6311.0 6326.0 6412.4 6433.4 6450.4 6456.2 6050.5 6203.5 6205.7 6225.2 6279.0 6259.4 6270.6 6291.7 6318.0 6318.9 4991.7 5072.2 5073.6 5074.5 5076.6 4406.4 4441.4 4501.6 4519.2 4569.9 4992.4 6057.8 6070.0 6125.0 6155.6 6402.5 6426.4 6426.5 6454.9 6457.3 5906.1 6094.8 6119.8 6127.9 6170.9 5591.6 5595.4 5596.9 5616.6 5629.6 6443.7 6663.2 6685.5 6693.5 6701.3 6288.6 6334.6 6340.4 6424.3 6464.3 5182.6 6579.3 6632.1 6672.7 6724.9 6213.1 6240.1 6340.2 6353.0 6363.1 6259.7 6325.6 6388.5 6389.5 6417.0 5445.4 6044.1 6238.3 6249.6 6310.1 6158.2 6197.1 6228.3 6282.4 6299.2 5169.7 6148.7 6218.8 6232.9 6259.3 6217.5 6279.6 6290.4 6301.5 6322.6 5081.5 6114.0 6147.0 6156.9 6227.1 18:33:24 INFO - PROCESS | 1667 | 18:33:24 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:67632.98 Median:38361.79 stddev:153438.76 (400.0%) stddev-sans-first:153786.43 18:33:24 INFO - PROCESS | 1667 | Values: 508.3 526.6 539.1 554.4 556.9 39350.4 42162.0 42234.6 42333.9 42341.8 30939.7 34258.1 39035.6 40501.8 42684.6 9608.0 9683.0 9745.4 9835.0 9849.1 8345.1 8601.6 9829.1 9843.0 9866.6 9864.0 9883.0 9894.5 9899.2 9908.7 9816.7 9907.6 9918.8 9922.2 9939.9 8273.7 9608.2 9647.7 9704.7 9722.6 40115.8 40272.8 40330.2 40392.8 40532.4 2835.3 3060.2 3086.9 3113.6 3430.9 23217.0 25016.0 27844.4 28505.1 28603.9 2663.1 2668.8 2669.6 2736.1 2796.4 406627.0 408573.0 410010.0 410063.0 412449.0 35529.5 35604.1 35782.6 35881.0 35923.0 36194.5 36968.3 37788.1 37846.0 37875.8 13064.6 14933.0 15472.0 15488.1 15536.8 12631.1 12995.8 13003.9 13129.4 13151.9 8327.8 8385.2 8388.2 8423.5 8444.1 3205.7 3263.5 3457.8 3468.6 3476.8 884.5 979.5 1023.9 1072.7 1091.9 25314.2 28483.0 29283.9 29746.0 29961.0 914.8 1087.5 1117.2 1123.4 1180.4 15121.4 15245.5 15286.8 15464.5 15664.2 600259.0 658529.7 668229.0 679138.0 686008.0 60770.0 61406.7 61547.4 62282.0 62363.1 1470.8 1474.6 1480.8 1483.9 1494.5 25542.7 27911.6 28785.8 29150.6 29572.0 53099.5 53140.5 53341.0 53710.0 53892.0 611593.9 615344.0 615656.0 616302.0 617641.0 25620.4 27509.0 27762.7 28157.3 28520.6 46559.8 46962.3 47291.0 47546.0 47936.5 38086.4 38415.6 38668.8 38741.6 39484.0 34514.7 37490.8 37792.6 38308.0 38507.8 33019.8 38107.8 38714.8 38884.8 39971.2 33017.8 33251.7 33404.8 34045.0 34121.7 23614.3 25319.0 25596.7 25658.0 25767.6 18:33:24 INFO - PROCESS | 1667 | 18:33:24 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:35129.31 Median:11703.20 stddev:101191.09 (864.6%) stddev-sans-first:101441.34 18:33:24 INFO - PROCESS | 1667 | Values: 394.7 399.8 400.8 400.9 420.4 14485.7 16845.0 20538.4 20860.3 21326.8 11824.8 12455.3 12475.6 20979.0 21281.9 5431.9 5529.2 5719.9 5732.9 5736.1 5532.0 5549.4 5558.2 5644.3 5648.4 5387.8 5410.2 5663.3 5669.8 5675.9 4662.9 4823.5 5589.7 5607.6 5692.4 4638.2 4991.0 5360.3 5375.7 5389.6 12088.3 13531.6 13906.5 13918.0 25389.6 1859.3 2124.5 2174.8 2186.4 2234.1 9919.0 9978.8 10085.0 11848.1 15047.5 1688.6 1710.9 1724.3 1746.5 1786.0 126779.0 135401.0 135628.0 137329.0 138658.0 11547.5 11692.9 11882.6 11966.8 12098.6 12644.7 15485.5 15529.5 15539.8 15555.7 8634.4 8703.1 8718.9 8750.6 8762.7 8485.2 8607.5 8609.0 8644.6 8657.8 4649.3 4696.1 4734.3 4745.9 4791.4 1979.8 2035.2 2041.2 2043.3 2051.1 857.1 862.5 867.5 977.3 1020.2 19790.0 20549.6 20621.4 20955.6 20996.1 824.7 919.6 928.2 1042.3 1050.1 10804.8 11109.7 11112.6 11118.2 11139.8 506846.0 609262.0 621150.0 632436.0 635052.0 103027.5 133055.0 134872.0 136590.0 139236.4 861.3 1001.4 1012.6 1013.0 1034.6 16622.5 16685.7 18220.8 18751.4 18886.1 51185.0 52770.5 54563.0 54929.0 56141.7 77455.2 77474.6 78427.0 80246.6 81618.2 7850.4 8091.1 8124.6 8305.1 8579.7 9125.0 10954.3 11157.3 12558.4 12905.3 10663.7 10766.1 10882.4 10890.9 11049.1 10437.7 10584.0 10608.4 10740.2 10855.7 9556.8 9730.0 10533.5 10809.4 11197.4 6864.6 8135.4 8167.1 8173.6 8324.5 10505.5 10684.1 11018.9 11158.6 11174.6 18:33:24 INFO - PROCESS | 1667 | 18:33:24 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5962.99 Median:2491.30 stddev:3422.92 (137.4%) stddev-sans-first:3417.16 18:33:24 INFO - PROCESS | 1667 | Values: 1647.2 1667.3 1946.9 1980.7 1992.7 8326.8 8896.4 8946.6 9429.6 9454.4 8055.2 9122.6 9224.9 9363.9 9400.4 2466.0 2471.8 2475.1 2500.2 2502.4 2447.3 2504.2 2506.1 2508.1 2525.9 2470.2 2472.9 2503.2 2507.9 2512.1 2068.0 2211.3 2401.0 2460.9 2478.5 2088.9 2380.0 2409.8 2422.6 2470.7 9261.9 9321.8 9342.0 9349.1 9376.7 5516.7 5571.6 5694.5 5699.5 5703.2 3324.1 3385.6 3624.5 3681.7 3696.8 1024.7 1043.6 1045.4 1045.9 1046.0 12150.9 12461.6 12586.1 12589.2 12614.6 3997.4 4998.3 5004.4 5063.8 5160.2 10594.2 10607.6 10631.9 10632.3 10644.9 4428.2 5492.8 5580.9 5602.6 5629.8 4103.8 4269.2 4515.5 5077.3 5079.6 3467.2 4150.4 4239.9 4274.0 4307.4 1366.7 1375.2 1375.5 1392.0 1404.5 879.9 907.1 920.9 926.9 961.0 5503.9 5995.2 6063.0 6252.2 6275.0 446.6 467.1 470.4 487.7 949.7 4035.4 4047.2 4091.4 4159.4 4159.9 11011.2 11376.5 11457.4 11501.2 11523.2 10641.5 10971.2 11309.0 11428.5 11454.9 8208.8 8523.1 8606.1 8610.7 8625.9 5650.2 5935.5 6173.2 6335.0 6360.7 10281.3 10425.8 10491.4 10605.8 10775.7 10693.2 10740.5 10781.8 10795.2 10848.8 7624.5 7794.2 7855.4 7933.6 8070.9 8788.0 8793.0 8797.6 8858.0 8895.4 7635.6 7851.8 7871.4 7986.2 7996.5 7573.3 7628.0 7671.6 7789.4 7792.1 6106.3 7103.8 7425.3 7465.5 7465.8 4983.6 5038.2 5163.5 5185.4 5190.5 5045.7 5143.9 5190.7 5235.8 5398.3 18:33:24 INFO - PROCESS | 1667 | 18:33:24 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4627.40 Median:5153.93 stddev:1432.68 (27.8%) stddev-sans-first:1399.80 18:33:24 INFO - PROCESS | 1667 | Values: 323.2 324.2 330.6 332.6 335.0 4934.3 5527.4 5583.7 5626.7 5628.3 4659.3 4962.3 4971.4 5353.1 5543.8 3251.0 3363.0 4113.3 4199.9 4204.6 3971.5 4061.9 4169.6 4176.0 4191.5 4029.5 4168.3 4195.9 4211.7 4224.1 3870.5 3918.4 3986.3 4163.0 4204.5 3341.7 3509.2 3512.2 3631.9 3680.9 4587.0 4850.6 4896.3 5224.6 5290.6 4094.3 4176.8 4589.9 4820.6 4870.6 4775.5 5527.4 5684.4 5711.3 5789.5 3243.4 3591.4 4054.8 4070.5 4175.4 4646.8 5669.3 5992.2 6038.6 6102.3 3921.8 3938.0 3943.8 3951.1 3956.8 4001.7 4027.2 4031.2 4040.7 4049.9 4159.9 4691.1 4725.1 4726.0 4740.2 4863.8 5316.8 5625.9 5833.9 5838.2 1229.9 1329.5 1397.9 1439.4 1450.7 857.4 864.4 890.2 916.5 948.1 4726.7 4917.2 5201.3 5362.0 5371.6 5207.5 5829.3 5913.7 5924.8 5944.5 4258.2 4604.1 4688.6 4879.9 5305.5 2579.5 2769.4 3053.8 3122.8 3122.8 5137.1 5884.3 6150.7 6169.8 6200.5 5172.6 5290.9 5754.8 6015.4 6103.0 5718.7 6172.7 6183.4 6186.0 6204.6 5295.7 5527.6 5821.8 5857.9 5984.6 5591.4 5962.3 5975.6 5983.4 6022.5 5265.4 5672.5 5805.4 6366.9 6389.1 3733.2 4046.8 4047.0 4064.6 4064.9 6052.9 6068.7 6090.6 6091.8 6108.9 5170.7 5175.7 5784.9 5810.6 5904.9 4991.4 5250.5 5286.8 5472.6 5689.5 5177.2 5392.6 5642.9 5706.9 5777.5 4691.9 4920.5 5107.3 5759.5 5804.9 5442.8 5701.9 5748.5 5754.4 5779.3 18:33:24 INFO - PROCESS | 1667 | 18:33:24 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:18252.35 Median:604.66 stddev:26093.45 (4315.4%) stddev-sans-first:26133.21 18:33:24 INFO - PROCESS | 1667 | Values: 658.3 724.8 738.3 740.2 759.0 13541.0 13654.5 13689.7 14178.3 14958.3 14328.1 14361.9 14367.2 14394.9 14396.3 730.9 896.2 905.4 918.3 933.9 913.8 917.2 917.8 918.8 923.0 965.8 972.5 977.2 980.7 983.1 931.6 954.4 968.7 971.9 975.6 712.3 724.9 727.3 728.1 732.6 14406.6 14494.1 14553.2 14594.0 14618.7 12316.9 12437.4 12452.0 12466.1 12733.6 9771.6 10012.8 10042.5 10144.7 10310.6 3389.5 3396.7 3416.9 3424.9 3474.8 58253.7 79560.0 79764.6 80447.6 82408.0 9346.9 9375.7 9422.4 9696.9 9749.3 21087.4 21103.5 21119.0 21121.5 21207.2 7290.7 7364.6 7388.4 7426.6 7506.0 4033.4 4111.7 4113.3 4116.3 4122.6 3418.7 3499.6 3504.4 3508.2 3522.3 584.1 588.4 597.1 615.2 624.0 537.8 578.1 578.5 591.7 596.3 8016.6 8032.1 8193.4 8272.9 8360.8 534.1 567.9 586.1 589.9 612.2 3710.8 3857.5 3890.6 3903.3 4105.2 73053.6 82175.0 94352.1 98423.0 98482.0 69110.0 88438.0 88611.0 89237.1 90478.0 72364.6 77642.4 78769.0 79894.6 80242.7 7251.3 7527.2 7696.2 7698.5 7758.2 36748.8 37611.6 47179.6 47268.0 48019.8 67351.0 67620.7 67824.0 67978.3 70304.0 11059.5 11174.1 11200.6 11224.3 11615.8 12012.2 12090.8 12144.6 12169.4 12597.2 11346.5 11383.7 11784.8 11891.3 12044.3 11389.5 11479.8 11623.8 11696.5 11756.2 8653.9 10785.9 10794.6 10978.5 11085.8 9560.8 9582.3 9714.9 9862.6 9989.7 8200.2 8666.9 9546.0 9619.3 9641.0 18:33:24 INFO - PROCESS | 1667 | -------- Summary: end -------- 18:33:24 INFO - PROCESS | 1667 | 18:33:25 INFO - TEST-INFO | 1667: exit 0 18:33:25 INFO - TEST-OK | dromaeo_css | took 1112581ms 18:33:25 INFO - TEST-START | kraken 18:33:25 INFO - operating with platform_type : mac_ 18:33:25 INFO - Initialising browser for kraken test... 18:33:25 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmp8FLhjC/profile http://localhost:49273/getInfo.html) 18:33:28 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 18:33:28 INFO - PROCESS | 1679 | colorDepth:24 18:33:28 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 18:33:28 INFO - PROCESS | 1679 | __metrics 18:33:28 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 18:33:29 INFO - TEST-INFO | 1679: exit 0 18:33:29 INFO - Browser initialized. 18:33:29 INFO - Running cycle 1/1 for kraken test... 18:33:29 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmp8FLhjC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:33:41 INFO - PROCESS | 1682 | RSS: Main: 249704448 18:33:41 INFO - PROCESS | 1682 | 18:33:44 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 18:33:45 INFO - PROCESS | 1682 | RSS: Main: 261218304 18:33:45 INFO - PROCESS | 1682 | 18:33:48 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 18:33:48 INFO - PROCESS | 1682 | RSS: Main: 266059776 18:33:48 INFO - PROCESS | 1682 | 18:33:52 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 18:33:52 INFO - PROCESS | 1682 | RSS: Main: 266436608 18:33:52 INFO - PROCESS | 1682 | 18:33:54 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 18:33:55 INFO - PROCESS | 1682 | RSS: Main: 267079680 18:33:55 INFO - PROCESS | 1682 | 18:33:57 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:33:58 INFO - PROCESS | 1682 | RSS: Main: 268075008 18:33:58 INFO - PROCESS | 1682 | 18:34:00 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 18:34:01 INFO - PROCESS | 1682 | RSS: Main: 268259328 18:34:01 INFO - PROCESS | 1682 | 18:34:04 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 18:34:05 INFO - PROCESS | 1682 | RSS: Main: 269029376 18:34:05 INFO - PROCESS | 1682 | 18:34:08 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 18:34:08 INFO - PROCESS | 1682 | RSS: Main: 268771328 18:34:08 INFO - PROCESS | 1682 | 18:34:10 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:34:11 INFO - PROCESS | 1682 | RSS: Main: 267706368 18:34:11 INFO - PROCESS | 1682 | 18:34:13 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:34:13 INFO - PROCESS | 1682 | RSS: Main: 268177408 18:34:13 INFO - PROCESS | 1682 | 18:34:16 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:34:16 INFO - PROCESS | 1682 | RSS: Main: 267743232 18:34:16 INFO - PROCESS | 1682 | 18:34:19 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:34:20 INFO - PROCESS | 1682 | RSS: Main: 267792384 18:34:20 INFO - PROCESS | 1682 | 18:34:23 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:34:24 INFO - PROCESS | 1682 | RSS: Main: 268046336 18:34:24 INFO - PROCESS | 1682 | 18:34:25 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 18:34:26 INFO - PROCESS | 1682 | RSS: Main: 269942784 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | __start_tp_report 18:34:26 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 18:34:26 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 18:34:26 INFO - PROCESS | 1682 | |i|pagename|runs| 18:34:26 INFO - PROCESS | 1682 | |0;ai-astar;116;112;117;130;159;114;116;115;116;116 18:34:26 INFO - PROCESS | 1682 | |1;audio-beat-detection;159;122;155;250;123;163;171;130;173;132 18:34:26 INFO - PROCESS | 1682 | |2;audio-dft;202;252;200;229;274;201;194;197;195;194 18:34:26 INFO - PROCESS | 1682 | |3;audio-fft;71;82;74;172;58;59;60;69;132;59 18:34:26 INFO - PROCESS | 1682 | |4;audio-oscillator;70;77;150;80;82;86;110;77;80;120 18:34:26 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;89;87;90;86;89;87;89;89;89;87 18:34:26 INFO - PROCESS | 1682 | |6;imaging-darkroom;105;95;95;99;95;94;96;95;104;96 18:34:26 INFO - PROCESS | 1682 | |7;imaging-desaturate;91;90;91;89;89;91;91;90;91;91 18:34:26 INFO - PROCESS | 1682 | |8;json-parse-financial;61;62;62;64;53;51;83;154;98;94 18:34:26 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;74;101;104;70;71;71;71;66;68;67 18:34:26 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;87;88;87;85;85;85;86;82;90;88 18:34:26 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;139;158;144;146;138;151;151;148;236;135 18:34:26 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;169;195;230;192;165;167;169;168;164;167 18:34:26 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;70;73;65;56;75;85;85;88;86;57 18:34:26 INFO - PROCESS | 1682 | __end_tp_report 18:34:26 INFO - PROCESS | 1682 | __start_cc_report 18:34:26 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1068 18:34:26 INFO - PROCESS | 1682 | __end_cc_report 18:34:26 INFO - PROCESS | 1682 | __startTimestamp1463103266196__endTimestamp 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | ------- Summary: start ------- 18:34:26 INFO - PROCESS | 1682 | Number of tests: 14 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:121.10 Median:116.00 stddev:14.17 (12.2%) stddev-sans-first:14.91 18:34:26 INFO - PROCESS | 1682 | Values: 116.0 112.0 117.0 130.0 159.0 114.0 116.0 115.0 116.0 116.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:157.80 Median:161.00 stddev:37.82 (23.5%) stddev-sans-first:40.11 18:34:26 INFO - PROCESS | 1682 | Values: 159.0 122.0 155.0 250.0 123.0 163.0 171.0 130.0 173.0 132.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:213.80 Median:201.50 stddev:28.34 (14.1%) stddev-sans-first:29.73 18:34:26 INFO - PROCESS | 1682 | Values: 202.0 252.0 200.0 229.0 274.0 201.0 194.0 197.0 195.0 194.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:83.60 Median:64.50 stddev:38.07 (59.0%) stddev-sans-first:40.11 18:34:26 INFO - PROCESS | 1682 | Values: 71.0 82.0 74.0 172.0 58.0 59.0 60.0 69.0 132.0 59.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:78.50 stddev:25.42 (32.4%) stddev-sans-first:25.54 18:34:26 INFO - PROCESS | 1682 | Values: 70.0 77.0 150.0 80.0 82.0 86.0 110.0 77.0 80.0 120.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:89.00 stddev:1.32 (1.5%) stddev-sans-first:1.36 18:34:26 INFO - PROCESS | 1682 | Values: 89.0 87.0 90.0 86.0 89.0 87.0 89.0 89.0 89.0 87.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:97.40 Median:95.00 stddev:3.98 (4.2%) stddev-sans-first:3.13 18:34:26 INFO - PROCESS | 1682 | Values: 105.0 95.0 95.0 99.0 95.0 94.0 96.0 95.0 104.0 96.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:91.00 stddev:0.84 (0.9%) stddev-sans-first:0.87 18:34:26 INFO - PROCESS | 1682 | Values: 91.0 90.0 91.0 89.0 89.0 91.0 91.0 90.0 91.0 91.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:78.20 Median:63.00 stddev:31.28 (49.7%) stddev-sans-first:32.56 18:34:26 INFO - PROCESS | 1682 | Values: 61.0 62.0 62.0 64.0 53.0 51.0 83.0 154.0 98.0 94.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:76.30 Median:70.50 stddev:14.02 (19.9%) stddev-sans-first:14.84 18:34:26 INFO - PROCESS | 1682 | Values: 74.0 101.0 104.0 70.0 71.0 71.0 71.0 66.0 68.0 67.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:87.00 stddev:2.21 (2.5%) stddev-sans-first:2.33 18:34:26 INFO - PROCESS | 1682 | Values: 87.0 88.0 87.0 85.0 85.0 85.0 86.0 82.0 90.0 88.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:149.50 stddev:29.43 (19.7%) stddev-sans-first:30.67 18:34:26 INFO - PROCESS | 1682 | Values: 139.0 158.0 144.0 146.0 138.0 151.0 151.0 148.0 236.0 135.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:169.00 stddev:21.22 (12.6%) stddev-sans-first:22.23 18:34:26 INFO - PROCESS | 1682 | Values: 169.0 195.0 230.0 192.0 165.0 167.0 169.0 168.0 164.0 167.0 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:80.00 stddev:11.99 (15.0%) stddev-sans-first:12.63 18:34:26 INFO - PROCESS | 1682 | Values: 70.0 73.0 65.0 56.0 75.0 85.0 85.0 88.0 86.0 57.0 18:34:26 INFO - PROCESS | 1682 | -------- Summary: end -------- 18:34:26 INFO - PROCESS | 1682 | 18:34:26 INFO - TEST-INFO | 1682: exit 0 18:34:27 INFO - TEST-OK | kraken | took 61551ms 18:34:27 INFO - SUITE-END | took 1174s 18:34:27 INFO - Completed test suite (00:19:34) 18:34:27 INFO - javascript benchmark 18:34:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7148.715462465193, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3502.2645244498663, 3512.5784905811306, 3540.911477213068, 3560.6083330833603, 3561.7506774525777, 5927.2590926134235, 5939.940600593993, 5977.641341519512, 5993.88012239755, 6041.486473649745, 5860.120981852724, 5938.228030356058, 5991.520678345732, 6012.60918040327, 6078.969605151968, 5246.7638956247065, 5306.204069389584, 5398.0, 5398.109311963522, 5499.835004949858, 383.6796953721282, 5331.786728530854, 5370.650907690988, 5399.244105825188, 5416.160495123262, 5354.116570765838, 5385.946140538584, 5386.757595908193, 5400.135978243482, 5420.783168673248, 5397.676139431607, 5416.350037995427, 5420.864478388033, 5426.131818908955, 5495.807646732345, 5218.008578370098, 5257.86855328616, 5332.280142180794, 5375.758090885881, 5401.162819762943, 5686.516646085088, 5812.825615231508, 5890.882182356372, 5912.793052243151, 5917.20117784098, 6207.596506227126, 6223.0, 6337.556371054028, 6344.333844946306, 6359.936400635981, 6264.248290205159, 6326.177596912418, 6332.525060620472, 6412.93587064128, 6423.454006409461, 5066.822661206877, 6107.938920610826, 6134.17188679527, 6138.109974053827, 6139.969300153515, 6179.258488981351, 6251.6874156292915, 6498.512611554058, 6527.706253218616, 6577.112089867806, 5466.398696143421, 6220.409061139185, 6268.0, 6292.811215663537, 6310.968445157837, 6325.96837015812, 6412.422881940648, 6433.388828061326, 6450.354964503512, 6456.192975878015, 6050.54620903434, 6203.47270482005, 6205.689715514206, 6225.221847269091, 6279.0, 6259.374062593704, 6270.561060725706, 6291.653959032297, 6317.989121740499, 6318.873622527523, 4991.725455099931, 5072.239164125337, 5073.644844860824, 5074.4671809460215, 5076.568491678175, 4406.427164468599, 4441.444819397575, 4501.594856462869, 4519.209138400766, 4569.908601827945, 4992.400911890596, 6057.7879774207695, 6069.969650151721, 6124.960759790135, 6155.569110162335, 6402.455791257796, 6426.389492998158, 6426.453751431087, 6454.870902582016, 6457.289698133295, 5906.084556893688, 6094.847628809315, 6119.776756597957, 6127.908081378694, 6170.907436388457, 5591.636543624732, 5595.38450770415, 5596.860078497989, 5616.6068375213345, 5629.605927585029, 6443.710033048618, 6663.233728121231, 6685.465162786868, 6693.497987650946, 6701.32986701326, 6288.5598008138995, 6334.588251763621, 6340.365963403623, 6424.325445828121, 6464.256610489854, 5182.559482444034, 6579.276279609144, 6632.104665870239, 6672.6997285121315, 6724.8991265132045, 6213.099100630293, 6240.095186198067, 6340.207474065742, 6352.968235158794, 6363.14097596837, 6259.6870156492905, 6325.557210995186, 6388.520860935356, 6389.456896163692, 6416.967915160394, 5445.428230035789, 6044.0933859919605, 6238.251409830849, 6249.625022498482, 6310.116583678197, 6158.230221222348, 6197.070439434121, 6228.34602366745, 6282.403171698681, 6299.181106456132, 5169.741512924264, 6148.692565371803, 6218.782342617987, 6232.906506402498, 6259.3427690091885, 6217.533684973736, 6279.560430769802, 6290.370962903856, 6301.495880329471, 6322.557420980487, 5081.517255860687, 6113.969430152821, 6146.985747351637, 6156.876862462816, 6227.097070924781], "value": 5812.111034892303, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.3010860067407, 526.5629527492181, 539.0889396919207, 554.3790954131373, 556.866776110615, 39350.40974385383, 42162.0, 42234.577654223445, 42333.941651458656, 42341.78829105854, 30939.69060309396, 34258.14354641135, 39035.609643903554, 40501.797491012505, 42684.57315426845, 9608.0, 9682.951585242065, 9745.366551174151, 9834.950825245864, 9849.064338887794, 8345.123762004992, 8601.569921503931, 9829.115379615869, 9843.0, 9866.556004979757, 9863.950680246624, 9883.0, 9894.50527473627, 9899.208063354914, 9908.653197138101, 9816.705498835046, 9907.603695852193, 9918.752031199278, 9922.206223502102, 9939.8509022364, 8273.710420135327, 9608.231341492734, 9647.662331818352, 9704.660336888175, 9722.562484688206, 40115.79652610426, 40272.798636006635, 40330.19339613221, 40392.798036009925, 40532.39201411981, 2835.31952331441, 3060.1890499017777, 3086.8732912487003, 3113.5641010258582, 3430.8627654893776, 23217.0, 25016.0, 27844.443111137665, 28505.144845654664, 28603.856980715173, 2663.1078588672817, 2668.8131830771854, 2669.586214136812, 2736.1107640017076, 2796.426732519849, 406627.0, 408573.0, 410010.0, 410063.0, 412448.999999997, 35529.46705799415, 35604.109897252776, 35782.64217357793, 35881.0, 35923.0, 36194.457083143774, 36968.26063478769, 37788.05529861776, 37846.0, 37875.81062094727, 13064.608061757972, 14933.0, 15472.0, 15488.07071575709, 15536.766948495782, 12631.11582189238, 12995.805062924064, 13003.869961300456, 13129.409176587076, 13151.934240328737, 8327.8334433311, 8385.245327920517, 8388.203120703523, 8423.452475589189, 8444.071152173256, 3205.7275131613605, 3263.494158405451, 3457.8098204598987, 3468.583769947572, 3476.756627036134, 884.5135175653365, 979.5298256836759, 1023.9078482936422, 1072.6942821295893, 1091.939943303126, 25314.240572782845, 28483.0, 29283.85358073196, 29745.958891439128, 29960.965822747163, 914.7530166855045, 1087.5269257872692, 1117.2402766118955, 1123.3596849795538, 1180.392098069495, 15121.395144194328, 15245.466408675644, 15286.847131528542, 15464.458743943907, 15664.216789160497, 600259.0, 658529.7073514601, 668229.0, 679138.0215494554, 686008.0, 60770.0, 61406.69296653488, 61547.384526154165, 62282.0, 62363.06455403173, 1470.7793830925448, 1474.5797447727346, 1480.7926890235376, 1483.9183844888419, 1494.4769330734373, 25542.72286385636, 27911.604419779334, 28785.848566057124, 29150.56274155932, 29572.0, 53099.46900530945, 53140.4685953151, 53341.0, 53710.0, 53892.0, 611593.9420303048, 615344.0, 615656.0, 616302.0, 617641.0, 25620.35949101213, 27509.0, 27762.722372776014, 28157.296067597654, 28520.57219141673, 46559.836004100165, 46962.29556556586, 47291.0, 47546.0, 47936.5206347932, 38086.42870356891, 38415.58506265437, 38668.806655967666, 38741.61258387493, 39484.0, 34514.65485345215, 37490.81254593709, 37792.62207378001, 38308.0, 38507.80746096364, 33019.83490082534, 38107.80946095363, 38714.8385548434, 38884.80557597307, 39971.2005759889, 33017.834910825295, 33251.6699332024, 33404.832975834965, 34045.0, 34121.658783412844, 23614.29157125289, 25319.0, 25596.744032559436, 25657.97368105255, 25767.613485797352], "value": 18342.000797091474, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.7473616885192, 399.8020979615087, 400.77957123582024, 400.8977710683776, 420.4093248985175, 14485.710285794277, 16845.0, 20538.38384848455, 20860.269890553835, 21326.786732132692, 11824.7635047299, 12455.314957677325, 12475.625731228056, 20978.951052447355, 21281.893590532025, 5431.891362172754, 5529.198266251391, 5719.914201286984, 5732.914006289909, 5736.053551164053, 5532.0, 5549.361823390302, 5558.249636299089, 5644.266245388094, 5648.43515648436, 5387.811426600069, 5410.161424979135, 5663.292088488939, 5669.801556945507, 5675.914861277084, 4662.906741865144, 4823.517648235166, 5589.664620122806, 5607.607467477278, 5692.402297758737, 4638.165130276549, 4991.0, 5360.276362691065, 5375.6774593524515, 5389.649672771257, 12088.274703517816, 13531.59405217845, 13906.48039387193, 13918.0, 25389.61915571268, 1859.33064096925, 2124.5155159288956, 2174.7607763146043, 2186.420598541388, 2234.1256223173777, 9919.0, 9978.750531236705, 10085.0, 11848.0521558276, 15047.548573542812, 1688.6116193275493, 1710.8973461592345, 1724.2758041622549, 1746.4858813285234, 1786.0, 126779.0, 135401.0, 135628.0, 137328.999999999, 138658.0, 11547.538098475967, 11692.941535292439, 11882.643520694393, 11966.820497692368, 12098.637040888787, 12644.747105058032, 15485.535433937, 15529.534113976599, 15539.84460155384, 15555.688886222211, 8634.352423568258, 8703.129687031373, 8718.912810871938, 8750.562471876381, 8762.737117886474, 8485.151484851465, 8607.526586037828, 8608.966923969163, 8644.610992505352, 8657.783555411039, 4649.325847752125, 4696.131215725101, 4734.337192793012, 4745.905081898342, 4791.425028996473, 1979.8416126709828, 2035.2062695548748, 2041.2345370486069, 2043.2542122125317, 2051.0975170924744, 857.1471385970999, 862.5299211929513, 867.5271976772674, 977.2768151567789, 1020.1736593359254, 19790.0, 20549.58900821975, 20621.38135855927, 20955.58088838245, 20996.055177516744, 824.6701319472139, 919.5907821019582, 928.1553786054793, 1042.296449896317, 1050.112654806687, 10804.783904322027, 11109.666709998713, 11112.61105861307, 11118.166137539716, 11139.777204455866, 506846.0, 609262.0, 621150.0, 632436.0, 635052.0, 103027.4848625767, 133055.0, 134872.0, 136589.97510273862, 139236.43054277715, 861.2851333393394, 1001.4241810958582, 1012.5544760305442, 1012.9898701012895, 1034.5861655337926, 16622.50132496027, 16685.74971375406, 18220.81779182191, 18751.437456876316, 18886.055697214808, 51185.0, 52770.47229527656, 54563.0, 54929.0, 56141.71929140328, 77455.2254477448, 77474.6126269365, 78427.0, 80246.5987670058, 81618.18381816344, 7850.371970242252, 8091.1099779025435, 8124.634391452517, 8305.127961563949, 8579.65681372738, 9124.95437522808, 10954.28797128216, 11157.27477713946, 12558.434870431036, 12905.290209038592, 10663.680089597325, 10766.138708903425, 10882.40146791934, 10890.891091088988, 11049.116070714163, 10437.68686939393, 10584.0, 10608.363498190134, 10740.194485413469, 10855.728606784893, 9556.805399325085, 9729.951350243487, 10533.473326333806, 10809.405482698527, 11197.384143872165, 6864.5881247125335, 8135.389845761473, 8167.060788009455, 8173.6321865515, 8324.458910171063, 10505.527251273557, 10684.145268378667, 11018.944905275743, 11158.609448669582, 11174.553017879194], "value": 9294.741931813329, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1647.176411794103, 1667.341400146942, 1946.9221231150755, 1980.6533856575086, 1992.6712092504738, 8326.791830204247, 8896.421732587385, 8946.597403116859, 9429.622815087389, 9454.432734035954, 8055.1944805519415, 9122.631605259197, 9224.861627075583, 9363.859542106873, 9400.435973841557, 2466.038245084414, 2471.8393304435153, 2475.121331927167, 2500.174942269056, 2502.399424138212, 2447.3269850791, 2504.198656429943, 2506.1228570000535, 2508.08454913956, 2525.936851578716, 2470.17249221511, 2472.938176545592, 2503.173952595639, 2507.887145078476, 2512.057978258153, 2067.9586408271825, 2211.3365990203038, 2401.00358351284, 2460.8523488590654, 2478.491909158618, 2088.9346433318965, 2380.0479808076734, 2409.7831195192343, 2422.576049191402, 2470.666460027909, 9261.861072083924, 9321.813563728756, 9341.953290233574, 9349.065093490664, 9376.67181648639, 5516.724163791834, 5571.637843540157, 5694.5159661428825, 5699.458551437607, 5703.201551782712, 3324.0858763839897, 3385.6106547747077, 3624.474451204587, 3681.7238707097076, 3696.759710618828, 1024.7079582319004, 1043.5512729526301, 1045.4041196517987, 1045.9372437653765, 1045.9790804183874, 12150.878491215062, 12461.563845265373, 12586.056045796602, 12589.181703189355, 12614.55849045279, 3997.360422332471, 4998.275250088716, 5004.424491183488, 5063.797448102108, 5160.174372100521, 10594.15246780271, 10607.57569697219, 10631.94684026575, 10632.255742098134, 10644.946775266075, 4428.225060614444, 5492.780288788484, 5580.944190558123, 5602.635828671123, 5629.802956896489, 4103.753774773523, 4269.23153832307, 4515.503294637587, 5077.339945807022, 5079.619028572872, 3467.2372078142766, 4150.439690641785, 4239.915201696011, 4273.97863010683, 4307.396964424983, 1366.7266546690503, 1375.1542801177404, 1375.4841934274648, 1391.9721605568034, 1404.4803422733653, 879.8944126704708, 907.1240000793966, 920.921721653667, 926.8702381666573, 960.9711708648751, 5503.9174412383845, 5995.190649262381, 6062.969685151546, 6252.218472690914, 6275.0, 446.64268585131765, 467.0868452175988, 470.36970459583637, 487.6903166489305, 949.7245798718433, 4035.3745169498775, 4047.2310261050307, 4091.386292056156, 4159.417681524589, 4159.9376009359885, 11011.174161937886, 11376.488058037408, 11457.369844658624, 11501.194916355775, 11523.193376463567, 10641.467926603793, 10971.23201375896, 11309.0, 11428.542858285742, 11454.885451145548, 8208.835823283502, 8523.06246312905, 8606.139386061344, 8610.741677749802, 8625.91374086251, 5650.180723794944, 5935.495482884004, 6173.1666225060835, 6334.968325158345, 6360.713767880364, 10281.280310378203, 10425.791484170426, 10491.422971736627, 10605.787884242118, 10775.730606734895, 10693.198010149426, 10740.462976851282, 10781.838272426077, 10795.190360723134, 10848.837267440837, 7624.5425274483705, 7794.181610930771, 7855.37157027425, 7933.642986065525, 8070.919290807252, 8788.033316335326, 8792.98880628736, 8797.560121994004, 8858.0, 8895.421797583136, 7635.580043097683, 7851.764447066597, 7871.370290376871, 7986.241307075818, 7996.5202087872585, 7573.2805383488485, 7628.0, 7671.578063206354, 7789.376849851885, 7792.1428642848105, 6106.32830388648, 7103.82240443993, 7425.331720145213, 7465.514741541782, 7465.776026719207, 4983.601311894967, 5038.168702164102, 5163.4578369270685, 5185.42960274377, 5190.480951904779, 5045.697258164522, 5143.871403214949, 5190.714510701948, 5235.764390602356, 5398.325209348832], "value": 4708.826424738269, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.1887961217344, 324.2331885091757, 330.5950210991535, 332.5643407136653, 334.9799012059275, 4934.3223304076655, 5527.364353099395, 5583.748731307091, 5626.690532020737, 5628.268325117734, 4659.277811939146, 4962.305277261186, 4971.353724015873, 5353.0899747042995, 5543.837903217738, 3250.959692898268, 3363.0, 4113.300738874398, 4199.853005144821, 4204.642605378546, 3971.5035620547433, 4061.939070913939, 4169.583041695836, 4176.035015139255, 4191.517975432834, 4029.468801855291, 4168.312228482297, 4195.85314513992, 4211.736479056286, 4224.134052519242, 3870.5161854768153, 3918.3534716771696, 3986.342253528179, 4162.959260184954, 4204.516480604739, 3341.7159541438764, 3509.2279698466295, 3512.227309991798, 3631.8547258109643, 3680.9325295664225, 4587.0, 4850.550472011647, 4896.314515967767, 5224.63427560071, 5290.576753859682, 4094.344904815215, 4176.770277634729, 4589.928129417013, 4820.590249828733, 4870.6103511718975, 4775.492866245214, 5527.429745168516, 5684.442001099522, 5711.257536520267, 5789.507891829199, 3243.399971005348, 3591.4018261873475, 4054.837806487737, 4070.450489183952, 4175.392473897541, 4646.79089440976, 5669.31968163823, 5992.161097446361, 6038.637681739066, 6102.267727872683, 3921.843126274974, 3937.960620393759, 3943.783091929914, 3951.091249012743, 3956.7625942443547, 4001.7398869073418, 4027.2146931348693, 4031.234065527599, 4040.676745860324, 4049.9392509112386, 4159.896002599899, 4691.061787642485, 4725.133572238947, 4725.976370118196, 4740.217864052462, 4863.781129849165, 5316.846677095782, 5625.915611265835, 5833.854153646108, 5838.241028666246, 1229.936105268936, 1329.5080820096625, 1397.860213978594, 1439.359485029173, 1450.6518435575385, 857.3955361470148, 864.4381152251087, 890.2077151335317, 916.5234078279391, 948.1419315519466, 4726.740029298421, 4917.188663870421, 5201.29782479368, 5362.0, 5371.597130215249, 5207.453217412117, 5829.329627092854, 5913.733881975321, 5924.7926322578505, 5944.494717948936, 4258.190943720745, 4604.128135648299, 4688.624910007191, 4879.878003049881, 5305.469453054741, 2579.5038877451034, 2769.418422131335, 3053.8167709937475, 3122.7970181938554, 3122.8126312421327, 5137.121473287644, 5884.264466941632, 6150.661713605795, 6169.81490555284, 6200.534959878028, 5172.603397082767, 5290.92063619046, 5754.798582049608, 6015.398460154037, 6102.9694851525455, 5718.683933007139, 6172.654391331265, 6183.350748171467, 6185.969070154801, 6204.5967012144965, 5295.7352132394, 5527.5577953764405, 5821.825345239649, 5857.912131318119, 5984.551158663205, 5591.412901645432, 5962.303371909271, 5975.55183361241, 5983.3545774912245, 6022.45797878193, 5265.447128051499, 5672.517835983987, 5805.390434004369, 6366.9044964326495, 6389.137466441971, 3733.2160246347485, 4046.838126474908, 4047.0, 4064.552899181147, 4064.8700500613772, 6052.852516691208, 6068.662235770221, 6090.589270008809, 6091.7563297467605, 6108.908366374597, 5170.741462926914, 5175.689458632343, 5784.94215057844, 5810.622309549865, 5904.940950590439, 4991.401031876053, 5250.474952504872, 5286.784039670821, 5472.644278121909, 5689.496621486698, 5177.197534382177, 5392.568594512508, 5642.85892852682, 5706.861226325587, 5777.537796976148, 4691.873950251983, 4920.507949205194, 5107.336046313955, 5759.481646651821, 5804.883902322014, 5442.836714898559, 5701.861801824316, 5748.54011679073, 5754.395788442153, 5779.266220133859], "value": 4177.595780134396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.3186402073853, 724.8224185074657, 738.3207449146785, 740.1636151149203, 759.0398146344871, 13541.0, 13654.453821847126, 13689.726205475898, 14178.291085445711, 14958.326875290611, 14328.140311581312, 14361.856381436182, 14367.209803460806, 14394.928025359859, 14396.280185990685, 730.9305615966475, 896.2113340260562, 905.425055090016, 918.2883265469248, 933.9066093390641, 913.7624217703378, 917.1791246834113, 917.8118485710413, 918.7978644698155, 922.9907700922972, 965.8068386322728, 972.5040229482944, 977.1889331854544, 980.6567701304558, 983.095552092077, 931.6320053578867, 954.4464210757744, 968.6900191938582, 971.8882328532168, 975.6146322202761, 712.2983860897012, 724.9383802376805, 727.323589062172, 728.1444302944072, 732.6117157906318, 14406.639834004129, 14494.130352178903, 14553.199574023425, 14593.9784215105, 14618.70762584753, 12316.876831231752, 12437.440315185748, 12452.0, 12466.065045121652, 12733.61799146027, 9771.560279787427, 10012.799744005079, 10042.548085336177, 10144.746381340525, 10310.639127630497, 3389.5254664347012, 3396.733718382802, 3416.8974930752115, 3424.9315013699834, 3474.7567670263093, 58253.70873145649, 79560.0, 79764.60117699433, 80447.5977620114, 82407.9999999994, 9346.859797103049, 9375.718728438158, 9422.434653920649, 9696.854547181656, 9749.31754777173, 21087.367378978655, 21103.472413189793, 21119.0, 21121.471963201042, 21207.15171393127, 7290.7083716651805, 7364.594947277951, 7388.44586656003, 7426.591537465383, 7506.009973576067, 4033.3546632538655, 4111.671066314688, 4113.259613269581, 4116.259073366823, 4122.649574786176, 3418.7265018798435, 3499.61504234534, 3504.351694936445, 3508.2281897982402, 3522.295540891832, 584.0596639410545, 588.3763210996358, 597.1401182297478, 615.1940957345892, 624.0265186309372, 537.8278950735727, 578.1356871477169, 578.5140481995144, 591.6568390333596, 596.3053043204704, 8016.55908925003, 8032.1164671886045, 8193.426460147852, 8272.917270827335, 8360.790980225544, 534.1347017831139, 567.9403662615448, 586.1237450012173, 589.9351071382144, 612.1827360473728, 3710.8144592770254, 3857.4599556062176, 3890.6109389060866, 3903.3383554039942, 4105.2405305018665, 73053.63473182707, 82175.0, 94352.0564794357, 98423.0, 98482.0, 69110.0, 88438.0, 88611.0, 89237.10762892418, 90478.0, 72364.63817680877, 77642.44715105667, 78769.0, 79894.60052699699, 80242.69739105373, 7251.311125443074, 7527.247275272429, 7696.191899850659, 7698.499597526143, 7758.205890085496, 36748.81625591855, 37611.62388376191, 47179.57434894894, 47268.0, 48019.75990120027, 67351.0, 67620.6618966902, 67824.0, 67978.32021679918, 70304.0, 11059.50232239535, 11174.106071514097, 11200.61829315407, 11224.326540407275, 11615.76768464643, 12012.159148859497, 12090.75818483643, 12144.574939877062, 12169.39153042362, 12597.181183223061, 11346.489407976496, 11383.658490245305, 11784.764304713686, 11891.345975971404, 12044.277343359427, 11389.544418223179, 11479.827802583135, 11623.825642615198, 11696.473658685209, 11756.23584467007, 8653.913460865311, 10785.946070269598, 10794.622188223375, 10978.505967231335, 11085.833712494481, 9560.808783824423, 9582.329236953625, 9714.854277185706, 9862.55618497155, 9989.650362237287, 8200.179982001991, 8666.913330866862, 9546.045395460398, 9619.326647134634, 9640.951795240979], "value": 6846.53445270954, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 117.0, 130.0, 159.0, 114.0, 116.0, 115.0, 116.0, 116.0], "value": 121.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 122.0, 155.0, 250.0, 123.0, 163.0, 171.0, 130.0, 173.0, 132.0], "value": 157.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 252.0, 200.0, 229.0, 274.0, 201.0, 194.0, 197.0, 195.0, 194.0], "value": 213.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 82.0, 74.0, 172.0, 58.0, 59.0, 60.0, 69.0, 132.0, 59.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 150.0, 80.0, 82.0, 86.0, 110.0, 77.0, 80.0, 120.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 86.0, 89.0, 87.0, 89.0, 89.0, 89.0, 87.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 95.0, 95.0, 99.0, 95.0, 94.0, 96.0, 95.0, 104.0, 96.0], "value": 97.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 89.0, 89.0, 91.0, 91.0, 90.0, 91.0, 91.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 64.0, 53.0, 51.0, 83.0, 154.0, 98.0, 94.0], "value": 78.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 101.0, 104.0, 70.0, 71.0, 71.0, 71.0, 66.0, 68.0, 67.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 85.0, 85.0, 85.0, 86.0, 82.0, 90.0, 88.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 158.0, 144.0, 146.0, 138.0, 151.0, 151.0, 148.0, 236.0, 135.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 195.0, 230.0, 192.0, 165.0, 167.0, 169.0, 168.0, 164.0, 167.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 65.0, 56.0, 75.0, 85.0, 85.0, 88.0, 86.0, 57.0], "value": 74.0, "unit": "score"}]}]} 18:34:27 INFO - Return code: 0 18:34:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:34:27 ERROR - # TBPL SUCCESS # 18:34:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:34:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:34:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:34:27 INFO - 2016-05-12 18:34:27.511 screenresolution[1687:10449] starting screenresolution argv=screenresolution get 18:34:27 INFO - 2016-05-12 18:34:27.514 screenresolution[1687:10449] Display 0: 1600x1200x32@60 18:34:27 INFO - 2016-05-12 18:34:27.520 screenresolution[1688:10452] starting screenresolution argv=screenresolution list 18:34:27 INFO - Available Modes on Display 0 18:34:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:34:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:34:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:34:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:34:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:34:27 INFO - Intel Iris: 18:34:27 INFO - Chipset Model: Intel Iris 18:34:27 INFO - Type: GPU 18:34:27 INFO - Bus: Built-In 18:34:27 INFO - VRAM (Dynamic, Max): 1536 MB 18:34:27 INFO - Vendor: Intel (0x8086) 18:34:27 INFO - Device ID: 0x0a2e 18:34:27 INFO - Revision ID: 0x0009 18:34:27 INFO - Displays: 18:34:27 INFO - Display: 18:34:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:34:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:34:27 INFO - Display Serial Number: 1600x1200 60 18:34:27 INFO - Main Display: Yes 18:34:27 INFO - Mirror: Off 18:34:27 INFO - Online: Yes 18:34:27 INFO - Rotation: Supported 18:34:27 INFO - Return code: 0 18:34:27 INFO - Running post-action listener: _resource_record_post_action 18:34:27 INFO - ##### 18:34:27 INFO - ##### Finished run-tests step (success) 18:34:27 INFO - ##### 18:34:27 INFO - Running post-run listener: _resource_record_post_run 18:34:28 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 37656064; Write bytes: 695288320; Read time: 393; Write time: 2032 18:34:28 INFO - TinderboxPrint: CPU usage
54.4% 18:34:28 INFO - TinderboxPrint: I/O read bytes / time
37,656,064 / 393 18:34:28 INFO - TinderboxPrint: I/O write bytes / time
695,288,320 / 2,032 18:34:28 INFO - TinderboxPrint: CPU idle
2,205.6 (45.6%) 18:34:28 INFO - TinderboxPrint: CPU system
972.9 (20.1%) 18:34:28 INFO - TinderboxPrint: CPU user
1,659.2 (34.3%) 18:34:28 INFO - TinderboxPrint: Swap in / out
432,054,272 / 0 18:34:28 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 384053760; Write bytes: 378119680; Read time: 28533; Write time: 736 18:34:28 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35886592; Write bytes: 297774080; Read time: 374; Write time: 1255 18:34:28 INFO - Running post-run listener: _upload_blobber_files 18:34:28 INFO - Blob upload gear active. 18:34:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:34:28 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 18:34:28 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'] 18:34:28 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 18:34:29 INFO - (blobuploader) - INFO - Open directory for files ... 18:34:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:34:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:34:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:30 INFO - (blobuploader) - INFO - Done attempting. 18:34:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:34:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:34:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:34:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:34:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:34:31 INFO - (blobuploader) - INFO - Done attempting. 18:34:31 INFO - (blobuploader) - INFO - Iteration through files over. 18:34:31 INFO - Return code: 0 18:34:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:34:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:34:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7fa586f6627705abd8ddf816ac922ef208cba70556ace0ec3f19e1dad684c72fdaca13cffaade5d2738acb233bcf44b31039e7ae3f16c9cca90c0120c939542f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96e78858b73541f9ef963482e861dd292b5d532d46dc9f4b75abeba4a99fd2775f5cd207b10ec9c35b14917ccf79e4afe77c864553a5772031541cf71bfc5a95"} 18:34:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:34:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:34:31 INFO - Contents: 18:34:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7fa586f6627705abd8ddf816ac922ef208cba70556ace0ec3f19e1dad684c72fdaca13cffaade5d2738acb233bcf44b31039e7ae3f16c9cca90c0120c939542f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96e78858b73541f9ef963482e861dd292b5d532d46dc9f4b75abeba4a99fd2775f5cd207b10ec9c35b14917ccf79e4afe77c864553a5772031541cf71bfc5a95"} 18:34:31 INFO - Running post-run listener: copy_logs_to_upload_dir 18:34:31 INFO - Copying logs to upload dir... 18:34:31 INFO - mkdir: /builds/slave/test/build/upload/logs 18:34:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.906700 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-12 18:34:31.357424) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 18:34:31.360850) ========= 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.g6lYxcuJFN/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.k7WhYznUHF/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7fa586f6627705abd8ddf816ac922ef208cba70556ace0ec3f19e1dad684c72fdaca13cffaade5d2738acb233bcf44b31039e7ae3f16c9cca90c0120c939542f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96e78858b73541f9ef963482e861dd292b5d532d46dc9f4b75abeba4a99fd2775f5cd207b10ec9c35b14917ccf79e4afe77c864553a5772031541cf71bfc5a95"} build_url:https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011337 build_url: 'https://queue.taskcluster.net/v1/task/HUCqfd7MRLS9gmy6j8HhqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7fa586f6627705abd8ddf816ac922ef208cba70556ace0ec3f19e1dad684c72fdaca13cffaade5d2738acb233bcf44b31039e7ae3f16c9cca90c0120c939542f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96e78858b73541f9ef963482e861dd292b5d532d46dc9f4b75abeba4a99fd2775f5cd207b10ec9c35b14917ccf79e4afe77c864553a5772031541cf71bfc5a95"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 18:34:31.391225) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 18:34:31.392027) ========= 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.g6lYxcuJFN/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.k7WhYznUHF/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.005233 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 18:34:31.443358) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 18:34:31.443961) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 18:34:31.444518) ========= ========= Total master_lag: 0.16 =========