builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0154 starttime: 1464720945.56 results: success (0) buildid: 20160531091142 builduid: f9b9e1951faf4dde8f928544fec6eaa9 revision: 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:45.563698) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:45.564436) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:45.564930) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:45.589297) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:45.589821) ========= 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.OMb5AssGKB/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.3xlYubOw3S/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-31 11:55:45-- 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% 8.24M=0.001s 2016-05-31 11:55:45 (8.24 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138961 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:45.749314) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:45.749630) ========= 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.OMb5AssGKB/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.3xlYubOw3S/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.020479 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:45.799612) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 11:55:45.803046) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 --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 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OMb5AssGKB/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.3xlYubOw3S/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-31 11:55:45,877 truncating revision to first 12 chars 2016-05-31 11:55:45,877 Setting DEBUG logging. 2016-05-31 11:55:45,877 attempt 1/10 2016-05-31 11:55:45,877 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6fb5bb73ea6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 11:55:47,090 unpacking tar archive at: fx-team-6fb5bb73ea6a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.496125 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 11:55:47.320153) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:47.320786) ========= script_repo_revision: 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:47.321265) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:47.321545) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 11:55:47.340819) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-05-31 11:55:47.341177) ========= /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.OMb5AssGKB/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.3xlYubOw3S/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:55:47 INFO - MultiFileLogger online at 20160531 11:55:47 in /builds/slave/test 11:55:47 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 11:55:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:55:47 INFO - {'append_to_log': False, 11:55:47 INFO - 'base_work_dir': '/builds/slave/test', 11:55:47 INFO - 'blob_upload_branch': 'Fx-Team', 11:55:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:55:47 INFO - 'branch': 'Fx-Team', 11:55:47 INFO - 'buildbot_json_path': 'buildprops.json', 11:55:47 INFO - 'config_files': ('talos/mac_config.py',), 11:55:47 INFO - 'default_actions': ('clobber', 11:55:47 INFO - 'read-buildbot-config', 11:55:47 INFO - 'download-and-extract', 11:55:47 INFO - 'populate-webroot', 11:55:47 INFO - 'create-virtualenv', 11:55:47 INFO - 'install', 11:55:47 INFO - 'run-tests'), 11:55:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:55:47 INFO - 'download_minidump_stackwalk': True, 11:55:47 INFO - 'download_symbols': 'ondemand', 11:55:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:55:47 INFO - 'tooltool.py': '/tools/tooltool.py', 11:55:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:55:47 INFO - '/tools/misc-python/virtualenv.py')}, 11:55:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:55:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:55:47 INFO - 'installer_path': 'installer.exe', 11:55:47 INFO - 'log_level': 'info', 11:55:47 INFO - 'log_name': 'talos', 11:55:47 INFO - 'log_to_console': True, 11:55:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:55:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:55:47 INFO - 'opt_config_files': (), 11:55:47 INFO - 'pip_index': False, 11:55:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:55:47 INFO - 'cmd': ('bash', 11:55:47 INFO - '-c', 11:55:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:55:47 INFO - 'enabled': True, 11:55:47 INFO - 'halt_on_failure': False, 11:55:47 INFO - 'name': 'check_screen_resolution'},), 11:55:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:55:47 INFO - 'cmd': ('bash', 11:55:47 INFO - '-c', 11:55:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:55:47 INFO - 'enabled': True, 11:55:47 INFO - 'halt_on_failure': False, 11:55:47 INFO - 'name': 'check_screen_resolution'},), 11:55:47 INFO - 'run_cmd_checks_enabled': True, 11:55:47 INFO - 'sps_profile': False, 11:55:47 INFO - 'sps_profile_interval': 0, 11:55:47 INFO - 'suite': 'dromaeojs-e10s', 11:55:47 INFO - 'system_bits': '32', 11:55:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:55:47 INFO - 'title': 't-yosemite-r7-0154', 11:55:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:55:47 INFO - 'use_talos_json': True, 11:55:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:55:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:55:47 INFO - 'work_dir': 'build'} 11:55:47 INFO - ##### 11:55:47 INFO - ##### Running clobber step. 11:55:47 INFO - ##### 11:55:47 INFO - Running pre-action listener: _resource_record_pre_action 11:55:47 INFO - Running main action method: clobber 11:55:47 INFO - rmtree: /builds/slave/test/build 11:55:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:55:49 INFO - Running post-action listener: _resource_record_post_action 11:55:49 INFO - ##### 11:55:49 INFO - ##### Finished clobber step (success) 11:55:49 INFO - ##### 11:55:49 INFO - ##### 11:55:49 INFO - ##### Running read-buildbot-config step. 11:55:49 INFO - ##### 11:55:49 INFO - Running pre-action listener: _resource_record_pre_action 11:55:49 INFO - Running main action method: read_buildbot_config 11:55:49 INFO - Using buildbot properties: 11:55:49 INFO - { 11:55:49 INFO - "project": "", 11:55:49 INFO - "product": "firefox", 11:55:49 INFO - "script_repo_revision": "production", 11:55:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 11:55:49 INFO - "repository": "", 11:55:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 11:55:49 INFO - "buildid": "20160531091142", 11:55:49 INFO - "pgo_build": "False", 11:55:49 INFO - "basedir": "/builds/slave/test", 11:55:49 INFO - "buildnumber": 694, 11:55:49 INFO - "slavename": "t-yosemite-r7-0154", 11:55:49 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:55:49 INFO - "platform": "yosemite_r7", 11:55:49 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 11:55:49 INFO - "branch": "fx-team", 11:55:49 INFO - "repo_path": "integration/fx-team", 11:55:49 INFO - "revision": "6fb5bb73ea6a50d383c23d7d346343b28b14b9f5", 11:55:49 INFO - "stage_platform": "macosx64", 11:55:49 INFO - "builduid": "f9b9e1951faf4dde8f928544fec6eaa9", 11:55:49 INFO - "slavebuilddir": "test" 11:55:49 INFO - } 11:55:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:55:49 INFO - Running post-action listener: _resource_record_post_action 11:55:49 INFO - ##### 11:55:49 INFO - ##### Finished read-buildbot-config step (success) 11:55:49 INFO - ##### 11:55:49 INFO - ##### 11:55:49 INFO - ##### Running download-and-extract step. 11:55:49 INFO - ##### 11:55:49 INFO - Running pre-action listener: _resource_record_pre_action 11:55:49 INFO - Running main action method: download_and_extract 11:55:49 INFO - mkdir: /builds/slave/test/build/tests 11:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:55:49 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:55:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:55:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:55:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:55:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 11:55:59 INFO - Downloaded 1199 bytes. 11:55:59 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:55:59 INFO - Using the following test package requirements: 11:55:59 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:55:59 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:55:59 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:55:59 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:55:59 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:55:59 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:55:59 INFO - u'jsshell-mac.zip'], 11:55:59 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:55:59 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:55:59 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:55:59 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:55:59 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:55:59 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:55:59 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:55:59 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:55:59 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:55:59 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:55:59 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:55:59 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:55: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')]} 11:55:59 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:55:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:55:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:55:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:55:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:56:02 INFO - Downloaded 18687095 bytes. 11:56:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:56:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:56:05 INFO - Return code: 0 11:56:05 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:05 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:56:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:56:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:56:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:56:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:56:08 INFO - Downloaded 18687095 bytes. 11:56:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:56:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:56:12 INFO - Return code: 0 11:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:12 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:56:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:56:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:56:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:56:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:56:13 INFO - Downloaded 10834055 bytes. 11:56:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:56:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:56:13 INFO - Return code: 0 11:56: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')]} 11:56:13 INFO - https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:56:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:56:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:56:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:56:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 11:56:16 INFO - Downloaded 110986559 bytes. 11:56:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:56:16 INFO - mkdir: /builds/slave/test/properties 11:56:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:56:16 INFO - Writing to file /builds/slave/test/properties/build_url 11:56:16 INFO - Contents: 11:56:16 INFO - build_url:https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:56:17 INFO - Running post-action listener: _resource_record_post_action 11:56:17 INFO - Running post-action listener: set_extra_try_arguments 11:56:17 INFO - ##### 11:56:17 INFO - ##### Finished download-and-extract step (success) 11:56:17 INFO - ##### 11:56:17 INFO - ##### 11:56:17 INFO - ##### Running populate-webroot step. 11:56:17 INFO - ##### 11:56:17 INFO - Running pre-action listener: _resource_record_pre_action 11:56:17 INFO - Running main action method: populate_webroot 11:56:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:56:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:56:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:56:17 INFO - '--tppagecycles', 11:56:17 INFO - '7'], 11:56:17 INFO - 'tests': ['tsvgm']}}, 11:56:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:56:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:56:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:56:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:56:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:17 INFO - 'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:56:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:56:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:17 INFO - 'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['damp', 'tps']}, 11:56:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:17 INFO - 'tests': ['damp', 'tps']}, 11:56:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['dromaeo_dom']}, 11:56:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:56:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['a11yr', 11:56:17 INFO - 'ts_paint', 11:56:17 INFO - 'tpaint', 11:56:17 INFO - 'sessionrestore', 11:56:17 INFO - 'sessionrestore_no_auto_restore', 11:56:17 INFO - 'tabpaint']}, 11:56:17 INFO - 'other-e10s': {'tests': ['a11yr', 11:56:17 INFO - 'ts_paint', 11:56:17 INFO - 'tpaint', 11:56:17 INFO - 'sessionrestore', 11:56:17 INFO - 'sessionrestore_no_auto_restore', 11:56:17 INFO - 'tabpaint']}, 11:56:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:56:17 INFO - 'ts_paint', 11:56:17 INFO - 'tpaint', 11:56:17 INFO - 'sessionrestore', 11:56:17 INFO - 'sessionrestore_no_auto_restore', 11:56:17 INFO - 'tabpaint']}, 11:56:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:56:17 INFO - 'ts_paint', 11:56:17 INFO - 'tpaint', 11:56:17 INFO - 'sessionrestore', 11:56:17 INFO - 'sessionrestore_no_auto_restore', 11:56:17 INFO - 'tabpaint']}, 11:56:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['a11yr', 11:56:17 INFO - 'ts_paint', 11:56:17 INFO - 'tpaint', 11:56:17 INFO - 'sessionrestore', 11:56:17 INFO - 'sessionrestore_no_auto_restore', 11:56:17 INFO - 'tabpaint']}, 11:56:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['a11yr', 11:56:17 INFO - 'ts_paint', 11:56:17 INFO - 'tpaint', 11:56:17 INFO - 'sessionrestore', 11:56:17 INFO - 'sessionrestore_no_auto_restore', 11:56:17 INFO - 'tabpaint']}, 11:56:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['tsvgx', 11:56:17 INFO - 'tsvgr_opacity', 11:56:17 INFO - 'tart', 11:56:17 INFO - 'tscrollx', 11:56:17 INFO - 'cart']}, 11:56:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:56:17 INFO - 'tsvgr_opacity', 11:56:17 INFO - 'tart', 11:56:17 INFO - 'tscrollx', 11:56:17 INFO - 'cart']}, 11:56:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:17 INFO - 'talos_options': ['--disable-e10s'], 11:56:17 INFO - 'tests': ['tp5o']}, 11:56:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:17 INFO - 'tests': ['tp5o']}, 11:56:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:17 INFO - 'talos_options': ['--disable-e10s', 11:56:17 INFO - '--xperf_path', 11:56:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:56:17 INFO - 'tests': ['tp5n']}, 11:56:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:56:17 INFO - 'talos_options': ['--xperf_path', 11:56:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:56:17 INFO - 'tests': ['tp5n']}}, 11:56:17 INFO - 'talos.zip': {'path': '', 11:56:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:56:17 INFO - Running post-action listener: _resource_record_post_action 11:56:17 INFO - ##### 11:56:17 INFO - ##### Finished populate-webroot step (success) 11:56:17 INFO - ##### 11:56:17 INFO - ##### 11:56:17 INFO - ##### Running create-virtualenv step. 11:56:17 INFO - ##### 11:56:17 INFO - Running pre-action listener: _resource_record_pre_action 11:56:17 INFO - Running main action method: create_virtualenv 11:56:17 INFO - Creating virtualenv /builds/slave/test/build/venv 11:56:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:56:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:56:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:56:17 INFO - Using real prefix '/tools/python27' 11:56:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:56:18 INFO - Installing distribute.............................................................................................................................................................................................done. 11:56:21 INFO - Installing pip.................done. 11:56:21 INFO - Return code: 0 11:56:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:21 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 0x10dbfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd718573540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, '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.OMb5AssGKB/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.3xlYubOw3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:56:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:56:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:56:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:21 INFO - 'HOME': '/Users/cltbld', 11:56:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:21 INFO - 'LOGNAME': 'cltbld', 11:56:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:21 INFO - 'MOZ_NO_REMOTE': '1', 11:56:21 INFO - 'NO_EM_RESTART': '1', 11:56:21 INFO - 'PAGER': '/bin/cat', 11:56:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:21 INFO - 'PWD': '/builds/slave/test', 11:56:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:21 INFO - 'SHELL': '/bin/bash', 11:56:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:56:21 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:56:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:21 INFO - 'USER': 'cltbld', 11:56:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:21 INFO - 'XPC_FLAGS': '0x0', 11:56:21 INFO - 'XPC_SERVICE_NAME': '0', 11:56:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:56:21 INFO - Cleaning up... 11:56:21 INFO - Return code: 0 11:56:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dbfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd718573540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, '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.OMb5AssGKB/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.3xlYubOw3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:56:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:56:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:56:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:21 INFO - 'HOME': '/Users/cltbld', 11:56:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:21 INFO - 'LOGNAME': 'cltbld', 11:56:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:21 INFO - 'MOZ_NO_REMOTE': '1', 11:56:21 INFO - 'NO_EM_RESTART': '1', 11:56:21 INFO - 'PAGER': '/bin/cat', 11:56:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:21 INFO - 'PWD': '/builds/slave/test', 11:56:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:21 INFO - 'SHELL': '/bin/bash', 11:56:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:56:21 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:56:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:21 INFO - 'USER': 'cltbld', 11:56:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:21 INFO - 'XPC_FLAGS': '0x0', 11:56:21 INFO - 'XPC_SERVICE_NAME': '0', 11:56:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:21 INFO - Downloading/unpacking psutil>=3.1.1 11:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:56:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:56:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:56:24 INFO - Installing collected packages: psutil 11:56:24 INFO - Running setup.py install for psutil 11:56:25 INFO - building 'psutil._psutil_osx' extension 11:56:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:56:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:56:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:56:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:56:25 INFO - building 'psutil._psutil_posix' extension 11:56:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:56:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:56:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:56:25 INFO - ^ 11:56:25 INFO - 1 warning generated. 11:56:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:56:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:56:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:56:25 INFO - Successfully installed psutil 11:56:25 INFO - Cleaning up... 11:56:25 INFO - Return code: 0 11:56:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dbfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd718573540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, '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.OMb5AssGKB/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.3xlYubOw3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:25 INFO - 'HOME': '/Users/cltbld', 11:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:25 INFO - 'LOGNAME': 'cltbld', 11:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:25 INFO - 'MOZ_NO_REMOTE': '1', 11:56:25 INFO - 'NO_EM_RESTART': '1', 11:56:25 INFO - 'PAGER': '/bin/cat', 11:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:25 INFO - 'PWD': '/builds/slave/test', 11:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:25 INFO - 'SHELL': '/bin/bash', 11:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:56:25 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:25 INFO - 'USER': 'cltbld', 11:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:25 INFO - 'XPC_FLAGS': '0x0', 11:56:25 INFO - 'XPC_SERVICE_NAME': '0', 11:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:56:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:56:28 INFO - Installing collected packages: mozsystemmonitor 11:56:28 INFO - Running setup.py install for mozsystemmonitor 11:56:28 INFO - Successfully installed mozsystemmonitor 11:56:28 INFO - Cleaning up... 11:56:28 INFO - Return code: 0 11:56:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:28 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 0x10dbfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd718573540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, '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.OMb5AssGKB/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.3xlYubOw3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:56:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:56:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:56:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:56:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:28 INFO - 'HOME': '/Users/cltbld', 11:56:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:28 INFO - 'LOGNAME': 'cltbld', 11:56:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:28 INFO - 'MOZ_NO_REMOTE': '1', 11:56:28 INFO - 'NO_EM_RESTART': '1', 11:56:28 INFO - 'PAGER': '/bin/cat', 11:56:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:28 INFO - 'PWD': '/builds/slave/test', 11:56:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:28 INFO - 'SHELL': '/bin/bash', 11:56:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:56:28 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:56:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:28 INFO - 'USER': 'cltbld', 11:56:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:28 INFO - 'XPC_FLAGS': '0x0', 11:56:28 INFO - 'XPC_SERVICE_NAME': '0', 11:56:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:29 INFO - Downloading/unpacking blobuploader==1.2.4 11:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:31 INFO - Downloading blobuploader-1.2.4.tar.gz 11:56:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:56:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:56:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:32 INFO - Downloading docopt-0.6.1.tar.gz 11:56:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:56:32 INFO - Installing collected packages: blobuploader, requests, docopt 11:56:32 INFO - Running setup.py install for blobuploader 11:56:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:56:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:56:32 INFO - Running setup.py install for requests 11:56:33 INFO - Running setup.py install for docopt 11:56:33 INFO - Successfully installed blobuploader requests docopt 11:56:33 INFO - Cleaning up... 11:56:33 INFO - Return code: 0 11:56:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:56: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')]} 11:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56: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')]} 11:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:33 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 0x10dbfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd718573540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, '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.OMb5AssGKB/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.3xlYubOw3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:56:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:56:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:56:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:33 INFO - 'HOME': '/Users/cltbld', 11:56:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:33 INFO - 'LOGNAME': 'cltbld', 11:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:33 INFO - 'MOZ_NO_REMOTE': '1', 11:56:33 INFO - 'NO_EM_RESTART': '1', 11:56:33 INFO - 'PAGER': '/bin/cat', 11:56:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:33 INFO - 'PWD': '/builds/slave/test', 11:56:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:33 INFO - 'SHELL': '/bin/bash', 11:56:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:56:33 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:56:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:33 INFO - 'USER': 'cltbld', 11:56:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:33 INFO - 'XPC_FLAGS': '0x0', 11:56:33 INFO - 'XPC_SERVICE_NAME': '0', 11:56:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:56:33 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-iOwRSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:56:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-AvoKAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:56:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-wXjMWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:56:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-934bIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:56:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-_E_bdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:56:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-avKDJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:56:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-rBSmI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:56:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-UZ8Y4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:56:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-IMCEBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:56:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:56:34 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ppnRBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:56:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-RwMoux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:56:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-WHMteF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:56:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-VR2R4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:56:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-o4qIVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:56:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-tSbMY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:56:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Ut3kUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:56:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-F2ue2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:56:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:56:35 INFO - Running setup.py install for manifestparser 11:56:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:56:35 INFO - Running setup.py install for mozcrash 11:56:36 INFO - Running setup.py install for mozdebug 11:56:36 INFO - Running setup.py install for mozdevice 11:56:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:56:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:56:36 INFO - Running setup.py install for mozfile 11:56:36 INFO - Running setup.py install for mozhttpd 11:56:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:56:36 INFO - Running setup.py install for mozinfo 11:56:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:56:36 INFO - Running setup.py install for mozInstall 11:56:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:56:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:56:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:56:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:56:36 INFO - Running setup.py install for mozleak 11:56:37 INFO - Running setup.py install for mozlog 11:56:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:56:37 INFO - Running setup.py install for moznetwork 11:56:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:56:37 INFO - Running setup.py install for mozprocess 11:56:37 INFO - Running setup.py install for mozprofile 11:56:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:56:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:56:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:56:37 INFO - Running setup.py install for mozrunner 11:56:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:56:37 INFO - Running setup.py install for mozscreenshot 11:56:38 INFO - Running setup.py install for moztest 11:56:38 INFO - Running setup.py install for mozversion 11:56:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:56:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:56:38 INFO - Cleaning up... 11:56:38 INFO - Return code: 0 11:56:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:38 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 0x10dbfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd718573540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, '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.OMb5AssGKB/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.3xlYubOw3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:56:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:56:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:56:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:38 INFO - 'HOME': '/Users/cltbld', 11:56:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:38 INFO - 'LOGNAME': 'cltbld', 11:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:38 INFO - 'MOZ_NO_REMOTE': '1', 11:56:38 INFO - 'NO_EM_RESTART': '1', 11:56:38 INFO - 'PAGER': '/bin/cat', 11:56:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:38 INFO - 'PWD': '/builds/slave/test', 11:56:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:38 INFO - 'SHELL': '/bin/bash', 11:56:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:56:38 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:56:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:38 INFO - 'USER': 'cltbld', 11:56:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:38 INFO - 'XPC_FLAGS': '0x0', 11:56:38 INFO - 'XPC_SERVICE_NAME': '0', 11:56:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:56:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-RuDoQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:56:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-wrZ4rJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:56:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-1mf4EE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:56:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:56:38 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-vuOfTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:56:39 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-UocpmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:56:39 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-2dEHs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:56:39 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-0YAAkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:56:39 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-MNRmUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:56:39 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-XaSA4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:56:39 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-RbXnAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:56:39 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-cblVop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:56:39 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-yIn41A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:56:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:56:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-sM_u8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:56:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-xWMKLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:56:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Cg_12F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:56:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-BepZnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:56:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-g0T81j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:56:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:42 INFO - Downloading blessings-1.6.tar.gz 11:56:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:56:43 INFO - Installing collected packages: blessings 11:56:43 INFO - Running setup.py install for blessings 11:56:43 INFO - Successfully installed blessings 11:56:43 INFO - Cleaning up... 11:56:43 INFO - Return code: 0 11:56:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:56:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:56:43 INFO - Reading from file tmpfile_stdout 11:56:43 INFO - Current package versions: 11:56:43 INFO - blessings == 1.6 11:56:43 INFO - blobuploader == 1.2.4 11:56:43 INFO - docopt == 0.6.1 11:56:43 INFO - manifestparser == 1.1 11:56:43 INFO - mozInstall == 1.12 11:56:43 INFO - mozcrash == 0.17 11:56:43 INFO - mozdebug == 0.1 11:56:43 INFO - mozdevice == 0.48 11:56:43 INFO - mozfile == 1.2 11:56:43 INFO - mozhttpd == 0.7 11:56:43 INFO - mozinfo == 0.9 11:56:43 INFO - mozleak == 0.1 11:56:43 INFO - mozlog == 3.2 11:56:43 INFO - moznetwork == 0.27 11:56:43 INFO - mozprocess == 0.22 11:56:43 INFO - mozprofile == 0.28 11:56:43 INFO - mozrunner == 6.11 11:56:43 INFO - mozscreenshot == 0.1 11:56:43 INFO - mozsystemmonitor == 0.3 11:56:43 INFO - moztest == 0.7 11:56:43 INFO - mozversion == 1.4 11:56:43 INFO - psutil == 3.1.1 11:56:43 INFO - requests == 1.2.3 11:56:43 INFO - wsgiref == 0.1.2 11:56:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:43 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 0x10dbfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd718573540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, '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.OMb5AssGKB/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.3xlYubOw3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:56:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:56:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:43 INFO - 'HOME': '/Users/cltbld', 11:56:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:43 INFO - 'LOGNAME': 'cltbld', 11:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:43 INFO - 'MOZ_NO_REMOTE': '1', 11:56:43 INFO - 'NO_EM_RESTART': '1', 11:56:43 INFO - 'PAGER': '/bin/cat', 11:56:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:43 INFO - 'PWD': '/builds/slave/test', 11:56:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:43 INFO - 'SHELL': '/bin/bash', 11:56:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:56:43 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:56:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:43 INFO - 'USER': 'cltbld', 11:56:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:43 INFO - 'XPC_FLAGS': '0x0', 11:56:43 INFO - 'XPC_SERVICE_NAME': '0', 11:56:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:56:43 INFO - Cleaning up... 11:56:43 INFO - Return code: 0 11:56:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56:43 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 0x10dbfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd718573540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, '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.OMb5AssGKB/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.3xlYubOw3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:56:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:56:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:56:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:43 INFO - 'HOME': '/Users/cltbld', 11:56:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:43 INFO - 'LOGNAME': 'cltbld', 11:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:43 INFO - 'MOZ_NO_REMOTE': '1', 11:56:43 INFO - 'NO_EM_RESTART': '1', 11:56:43 INFO - 'PAGER': '/bin/cat', 11:56:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:43 INFO - 'PWD': '/builds/slave/test', 11:56:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:43 INFO - 'SHELL': '/bin/bash', 11:56:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:56:43 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:56:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:43 INFO - 'USER': 'cltbld', 11:56:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:43 INFO - 'XPC_FLAGS': '0x0', 11:56:43 INFO - 'XPC_SERVICE_NAME': '0', 11:56:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:44 INFO - Downloading/unpacking jsonschema 11:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:56:46 INFO - Downloading/unpacking functools32 (from jsonschema) 11:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:46 INFO - Downloading functools32-3.2.3-2.tar.gz 11:56:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:56:47 INFO - warning: no files found matching '*.txt' 11:56:47 INFO - no previously-included directories found matching 'build' 11:56:47 INFO - no previously-included directories found matching 'dist' 11:56:47 INFO - no previously-included directories found matching '.git*' 11:56:47 INFO - Installing collected packages: jsonschema, functools32 11:56:47 INFO - Running setup.py install for functools32 11:56:47 INFO - warning: no files found matching '*.txt' 11:56:47 INFO - no previously-included directories found matching 'build' 11:56:47 INFO - no previously-included directories found matching 'dist' 11:56:47 INFO - no previously-included directories found matching '.git*' 11:56:47 INFO - Successfully installed jsonschema functools32 11:56:47 INFO - Cleaning up... 11:56:47 INFO - Return code: 0 11:56:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:56: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')]} 11:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:56:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56: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')]} 11:56:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:56:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:56: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dbfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd718573540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, '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.OMb5AssGKB/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.3xlYubOw3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:56: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', 'flake8'] in /builds/slave/test/build 11:56: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 flake8 11:56:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:56:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:56:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:56:47 INFO - 'HOME': '/Users/cltbld', 11:56:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:56:47 INFO - 'LOGNAME': 'cltbld', 11:56:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:56:47 INFO - 'MOZ_NO_REMOTE': '1', 11:56:47 INFO - 'NO_EM_RESTART': '1', 11:56:47 INFO - 'PAGER': '/bin/cat', 11:56:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:56:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:56:47 INFO - 'PWD': '/builds/slave/test', 11:56:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:56:47 INFO - 'SHELL': '/bin/bash', 11:56:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:56:47 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:56:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:56:47 INFO - 'USER': 'cltbld', 11:56:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:56:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:56:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:56:47 INFO - 'XPC_FLAGS': '0x0', 11:56:47 INFO - 'XPC_SERVICE_NAME': '0', 11:56:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:56:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:56:47 INFO - Downloading/unpacking flake8 11:56: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 11:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56: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 11:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:56:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:56:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:56:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:56:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:56:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:56:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:56:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:56:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:56:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:56:51 INFO - no previously-included directories found matching 'docs/_build' 11:56:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:56:51 INFO - Running setup.py install for pep8 11:56:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:56:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:56:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:56:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:56:51 INFO - no previously-included directories found matching 'docs/_build' 11:56:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:56:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:56:51 INFO - Cleaning up... 11:56:51 INFO - Return code: 0 11:56:51 INFO - Running post-action listener: _resource_record_post_action 11:56:51 INFO - Running post-action listener: _start_resource_monitoring 11:56:51 INFO - Starting resource monitoring. 11:56:51 INFO - ##### 11:56:51 INFO - ##### Finished create-virtualenv step (success) 11:56:51 INFO - ##### 11:56:51 INFO - ##### 11:56:51 INFO - ##### Running install step. 11:56:51 INFO - ##### 11:56:51 INFO - Running pre-action listener: _resource_record_pre_action 11:56:51 INFO - Running main action method: install 11:56:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:56:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:56:52 INFO - Reading from file tmpfile_stdout 11:56:52 INFO - Detecting whether we're running mozinstall >=1.0... 11:56:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:56:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:56:52 INFO - Reading from file tmpfile_stdout 11:56:52 INFO - Output received: 11:56:52 INFO - Usage: mozinstall [options] installer 11:56:52 INFO - Options: 11:56:52 INFO - -h, --help show this help message and exit 11:56:52 INFO - -d DEST, --destination=DEST 11:56:52 INFO - Directory to install application into. [default: 11:56:52 INFO - "/builds/slave/test"] 11:56:52 INFO - --app=APP Application being installed. [default: firefox] 11:56:52 INFO - mkdir: /builds/slave/test/build/application 11:56:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:56:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:57:26 INFO - Reading from file tmpfile_stdout 11:57:26 INFO - Output received: 11:57:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:57:26 INFO - Running post-action listener: _resource_record_post_action 11:57:26 INFO - ##### 11:57:26 INFO - ##### Finished install step (success) 11:57:26 INFO - ##### 11:57:26 INFO - ##### 11:57:26 INFO - ##### Running run-tests step. 11:57:26 INFO - ##### 11:57:26 INFO - Running pre-action listener: _resource_record_pre_action 11:57:26 INFO - Running main action method: run_tests 11:57:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:57:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:57:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:57:26 INFO - 2016-05-31 11:57:26.271 screenresolution[2495:23136] starting screenresolution argv=screenresolution get 11:57:26 INFO - 2016-05-31 11:57:26.274 screenresolution[2495:23136] Display 0: 1600x1200x32@60 11:57:26 INFO - 2016-05-31 11:57:26.280 screenresolution[2496:23138] starting screenresolution argv=screenresolution list 11:57:26 INFO - Available Modes on Display 0 11:57:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:57:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:57:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:57:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:57:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:57:26 INFO - Intel Iris: 11:57:26 INFO - Chipset Model: Intel Iris 11:57:26 INFO - Type: GPU 11:57:26 INFO - Bus: Built-In 11:57:26 INFO - VRAM (Dynamic, Max): 1536 MB 11:57:26 INFO - Vendor: Intel (0x8086) 11:57:26 INFO - Device ID: 0x0a2e 11:57:26 INFO - Revision ID: 0x0009 11:57:26 INFO - Displays: 11:57:26 INFO - Display: 11:57:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:57:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:57:26 INFO - Display Serial Number: 1600x1200 60 11:57:26 INFO - Main Display: Yes 11:57:26 INFO - Mirror: Off 11:57:26 INFO - Online: Yes 11:57:26 INFO - Rotation: Supported 11:57:26 INFO - Return code: 0 11:57:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:57:26 INFO - Python 2.7.3 11:57:26 INFO - Return code: 0 11:57:26 INFO - grabbing minidump binary from tooltool 11:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:57:26 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 0x10daa1d30>, '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 0x10d8c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da98b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:57:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:57:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:57:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:57:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:57:26 INFO - Return code: 0 11:57:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:57:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:57:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:57:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:57:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:57:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:57:27 INFO - Return code: 0 11:57:27 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/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 11:57:27 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/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --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 11:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OMb5AssGKB/Render', 11:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:27 INFO - 'HOME': '/Users/cltbld', 11:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:27 INFO - 'LOGNAME': 'cltbld', 11:57:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:57:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:27 INFO - 'MOZ_NO_REMOTE': '1', 11:57:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:57:27 INFO - 'NO_EM_RESTART': '1', 11:57:27 INFO - 'PAGER': '/bin/cat', 11:57:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:27 INFO - 'PWD': '/builds/slave/test', 11:57:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:27 INFO - 'SHELL': '/bin/bash', 11:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3xlYubOw3S/Listeners', 11:57:27 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 11:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:27 INFO - 'USER': 'cltbld', 11:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:27 INFO - 'XPC_FLAGS': '0x0', 11:57:27 INFO - 'XPC_SERVICE_NAME': '0', 11:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:27 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/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 11:57:27 INFO - mozversion application_buildid: 20160531091142 11:57:27 INFO - mozversion application_changeset: 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 11:57:27 INFO - mozversion application_display_name: Nightly 11:57:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:57:27 INFO - mozversion application_name: Firefox 11:57:27 INFO - mozversion application_remotingname: firefox 11:57:27 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:57:27 INFO - mozversion application_vendor: Mozilla 11:57:27 INFO - mozversion application_version: 49.0a1 11:57:27 INFO - mozversion platform_buildid: 20160531091142 11:57:27 INFO - mozversion platform_changeset: 6fb5bb73ea6a50d383c23d7d346343b28b14b9f5 11:57:27 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:57:27 INFO - mozversion platform_version: 49.0a1 11:57:27 INFO - using testdate: 1464721047 11:57:27 INFO - actual date: 1464721047 11:57:27 INFO - starting webserver on 'localhost:54391' 11:57:27 INFO - SUITE-START | Running 2 tests 11:57:27 INFO - TEST-START | dromaeo_css 11:57:27 INFO - operating with platform_type : mac_ 11:57:27 INFO - Initialising browser for dromaeo_css test... 11:57:27 INFO - TEST-INFO | started process 2514 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp08AhkY/profile http://localhost:54391/getInfo.html) 11:57:31 INFO - PROCESS | 2514 | __metrics Screen width/height:1600/1200 11:57:31 INFO - PROCESS | 2514 | colorDepth:24 11:57:31 INFO - PROCESS | 2514 | Browser inner width/height: 1024/689 11:57:31 INFO - PROCESS | 2514 | __metrics 11:57:31 ERROR - PROCESS | 2514 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 11:57:31 INFO - TEST-INFO | 2514: exit 0 11:57:31 INFO - Browser initialized. 11:57:31 INFO - Running cycle 1/1 for dromaeo_css test... 11:57:31 INFO - TEST-INFO | started process 2517 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp08AhkY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:57:43 INFO - PROCESS | 2517 | RSS: Main: 252801024 11:57:43 INFO - PROCESS | 2517 | 11:58:33 INFO - PROCESS | 2517 | 1464721113557 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] 12:00:45 INFO - PROCESS | 2517 | Cycle 1(1): loaded http://localhost:54391/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54391/tests/dromaeo/cssquery-ext.html) 12:00:46 INFO - PROCESS | 2517 | RSS: Main: 264769536 12:00:46 INFO - PROCESS | 2517 | 12:03:48 INFO - PROCESS | 2517 | Cycle 1(1): loaded http://localhost:54391/tests/dromaeo/cssquery-ext.html (next: http://localhost:54391/tests/dromaeo/cssquery-jquery.html) 12:03:49 INFO - PROCESS | 2517 | RSS: Main: 276066304 12:03:49 INFO - PROCESS | 2517 | 12:06:51 INFO - PROCESS | 2517 | Cycle 1(1): loaded http://localhost:54391/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54391/tests/dromaeo/cssquery-mootools.html) 12:06:52 INFO - PROCESS | 2517 | RSS: Main: 278450176 12:06:52 INFO - PROCESS | 2517 | 12:09:53 INFO - PROCESS | 2517 | Cycle 1(1): loaded http://localhost:54391/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54391/tests/dromaeo/cssquery-prototype.html) 12:09:54 INFO - PROCESS | 2517 | RSS: Main: 279707648 12:09:54 INFO - PROCESS | 2517 | 12:12:57 INFO - PROCESS | 2517 | Cycle 1(1): loaded http://localhost:54391/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54391/tests/dromaeo/cssquery-yui.html) 12:12:57 INFO - PROCESS | 2517 | RSS: Main: 283639808 12:12:57 INFO - PROCESS | 2517 | 12:16:00 INFO - PROCESS | 2517 | Cycle 1(1): loaded http://localhost:54391/tests/dromaeo/cssquery-yui.html (next: http://localhost:54391/tests/dromaeo/cssquery-dojo.html) 12:16:00 INFO - PROCESS | 2517 | RSS: Main: 283770880 12:16:00 INFO - PROCESS | 2517 | 12:16:00 INFO - PROCESS | 2517 | __start_tp_report 12:16:00 INFO - PROCESS | 2517 | _x_x_mozilla_page_load 12:16:00 INFO - PROCESS | 2517 | _x_x_mozilla_page_load_details 12:16:00 INFO - PROCESS | 2517 | |i|pagename|runs| 12:16:00 INFO - PROCESS | 2517 | |0;dojo.html;3045.8020228685145;3480.33873564023;3491.4762785582166;3502.4746288056804;3507.6316986716392;5384.757685904139;5817.272840894888;5828.708564571775;5911.674857882815;5922.2597175353085;5832.650040997543;5856.945749765051;5925.229720136376;5980.850478738033;5993.970030149854;5199.376074871021;5220.973895130519;5225.790968361261;5338.839834804962;5365.678059316453;5316.867078323054;5349.5185433311;5352.277442545245;5433.972830135844;5477;5157.149070403379;5222.686638801646;5264.736763161845;5271.841844744664;5310.0441920454305;5256.684598924076;5269.525742683139;5271.841844744664;5284.630075894689;5302.575793936476;5199.870003249949;5230.503102205284;5241.685498870042;5273.024490469275;5300.92048619271;5579.274694289757;5830.15462757902;5841.970790146064;5906.527477801766;5929.851753706192;6099.817005489843;6150.938490615082;6169.598976066586;6186.81439556814;6207.906881396782;6109.890546867989;6136.723847426876;6209.161763162006;6222.097795819581;6226.40849119333;5954.106883967396;5963.821085367446;5974.044152935509;5982.4017598239825;6030.427109424598;6327.746890124436;6343.809685709436;6370.076338930921;6390.137331460193;6393.392627700361;5618.624221013767;5732.742026608813;6100.97480593228;6125.540584456184;6137.478314343331;6176.011838105971;6215.595986260879;6222.28443728977;6223.408776166257;6232.781852635227;5260.8952120054455;6087.512998960073;6114.358196333954;6119.938800612026;6130.111133885562;6011.398860114041;6102.816915492543;6115.235595550556;6151.72317245725;6173.59871608353;4977.352944117314;4984.42679091909;4993.7503124843615;5013.1227035268685;5024.020316038411;4458.799354029076;4482.574155455227;4490.865274041784;4508.684392092522;4516.751578663205;5745.253117094834;5910.379410161957;5948.256467941507;5958.523318134538;5976.61152025117;6178.814635560941;6182.629042257389;6208.50331973441;6221.160143380676;6223.097650840603;5970.104484327298;5974.372690867396;5984.102384642339;6013.909791353133;6041.426064523863;5340.118880384693;5437.4562543745305;5437.728113594305;5455.509004189642;5471;5322.38792538863;5643.915341269884;6305.400986906145;6458.38645328693;6486.383793539606;6130.50955923534;6212.751489940532;6214.9067763984485;6215.81352559424;6242.968785156045;6304.84237894038;6334.303226644974;6363.045543168377;6413.422791948747;6444.13004244421;5034.798608055637;5631.183478395692;6090.969545152246;6143.354947730602;6161.4146656067605;6100.853682747703;6130.50955923534;6172.969135154295;6190.41191086846;6195.1636529068;6051.243594550681;6070;6072.939270607238;6112.816615501542;6156.230471191101;6084.14821924922;6084.239470066242;6096.969515152396;6106.877862442815;6107.328193898757;4559.4756602989755;5983.341832398346;6017.578769486094;6079.118527813531;6127.142200091902;6141.096260159365;6141.324454309933;6144.631322120687;6145.139680444652;6146.108814222002;6147.385261473817;6156.076588511759;6158.722857471335;6164.845878853064;6165.475934545614 12:16:00 INFO - PROCESS | 2517 | |1;ext.html;508.41024411682434;518.4219595151405;528.6431658630423;562.5162360370082;588.8145234251214;31401.685983140163;38053.23893522128;39601.801990990076;39676;39695.206095878064;40656;41366;41458.37812432808;41465.79267103668;41499.79250103745;9279.350445468837;9509.762255943588;9531.616276221945;9542.618295268181;9579.904200957972;9414.62341506339;9469.479178645171;9494.05059494052;9506.096920792514;9543.665971690993;9316.021817709145;9446.338756287028;9516.904830951706;9517.5717092731;9520.095590918852;9466.24270058397;9488.952555237249;9513.096255855682;9519.476428796414;9523.238140948743;8337.499750015018;9399.919009313948;9406.200472959807;9436.00921903204;9512.28657850664;30938;32624.347513049845;32924.189169595666;36707.81646091752;38770;2759.7930155238237;3306.996178142155;3320.9501857472155;3449.6032956210106;3483.8954831354845;30308.242293942607;30409;30606.846965765028;31330.5901234445;31452.37095258082;2840.985795071032;3141.277506173593;3168.4138434389483;3170.1282147409415;3171.57183780189;400540.99188512197;454235;456452.4354756443;473005;473338;34696;34967.65032349695;35055;35108.824455877555;35265.64734352675;35359.4696079559;35814.820925895205;36044.459333110026;36124;37153.814230929216;15444.0733555987;15536.223188840513;15552.066875987477;15593.064416135068;15609.453669121527;12867.871321286855;12902.612921612366;12914.870851291555;12934.547290844775;12960.935195323964;19693.40919772409;20018.69971950422;20210.88840113808;20223.988800560208;20257.189712411633;6879.277675844064;7383.483156179123;7617.286110625065;7996.280334769898;8153.5923203839575;894.1148263219366;1000.0649392817738;1029.6910926721953;1059.4914441068331;1162.9069674426196;27292.590611140848;30205;30323.393532129234;30359.544606830914;31316.9039083631;1070.5878236878857;1105.209775010855;1106.833974903771;1173.1609504997925;1223.9816402753966;12358.50565977351;15931.044137351795;15946.963447375883;16101.033937963759;16426.917865410596;582143;583816;592500;592583;595405;59296.407035929085;59340.813183736085;59600.10599841006;59882;60053.69973150106;1386.729587730383;1391.5964370332692;1423.7294913966314;1425.8788003019858;1427.336288625794;29490.262743431587;30350.544741829333;30352;30427.087187384415;30474;51466.74266628643;51955.74022129865;52362;52364.47635523596;52602.73698631483;531309;532989.3350533377;534716;534823;535777;24698.50602987966;24783.380415489755;24981.37546561278;25246.873765631055;27327.86336068307;48518;48863;49388.75305623449;49718.00563988772;49918;39723.20553588854;40085.398719019824;40287;40748;40938.59061409467;39091.609083909934;39266.87697791691;39451.05489451032;39736.096526856265;39893.800530997156;37838.62161378351;38333.041673958374;38659.2268154641;38771.6122838768;38810.80594597009;32891.67108328982;34453;35050.649493505756;35446.822765886005;35913.281734365686;23742.762572374057;23750.406239844142;25213.495730085662;25544.616830747924;25731.35671608225 12:16:00 INFO - PROCESS | 2517 | |2;jquery.html;373.1548043681063;381.9115520765817;385.37569137996445;391.06730447881796;401.6004075944436;19032.42902712919;20685.586288274226;21503.204583404175;21805.345839624795;21842.235521756742;16032.43886463974;18461.630767384646;18759.718604220914;21400.036998335072;21438.57122857546;5259.5529380002745;5776.653400795944;5979.402059794008;5993.28080630325;5998.340182579913;5929;6011.789587364443;6014;6016.548758843082;6031.24609423822;5430.456954304577;5839.211706419643;5848.181254624356;5920.289565252176;5950;5879.323877754049;5950.226470558821;6045.72794224261;6585.374389432996;6605.306442823482;5842.883142337172;5864.794732184353;5869.911951320733;5881.000229960917;5894.705264736746;13476.056676032682;13493.987950903722;13498;13507.054506184571;14321.997460177792;2071.099071903712;2117.676452217364;2129.3824790810645;2265.920692775745;2375.964360534593;9701.205277023773;10041.949790251076;10050.9497452513;10219.386836789816;10419.583216671324;1834.926602935881;1875.4467432107424;1947.0264867566216;1953.540917884296;1957.7944315846773;96759;113278.43360783225;121852.39073804574;122995.77004230022;124813;8534.615942282611;11387.487563059516;11517.596884109016;11707.239029463057;11824.467898944567;14407.42370305197;14780.778288325684;14796.48212312564;14799.778003329959;14842.589953954357;8602.612882420306;8676.045634980148;8826.691065812665;8920.509371984603;8988.10118988096;7600.543967361976;8502.06477287498;8811.339149563808;8821.558922054;8950.104989500998;3527.5237842891393;4561.340442953326;4571.725696458223;4633.86098417048;4682.81268749253;1718.802337731152;1744.7437844751757;1871.5321169707572;1978.6636271833822;2004.2484068474323;844.2697067037092;906.5286051253312;969;984.1142971325864;1035.5909415780725;17946.64106717858;20358;21357.572848543252;21582.028808703646;21887.79617121042;952.6998995316453;959.5106495687111;967.2165545907837;970.0541971577796;988.5403287471361;9007.369484136048;9652.27607929408;10598.470076496144;11490.195686302046;11504.827427588592;592999;610425;623577;624331;636086;121773;134364;135796.3210183943;137440;138537;1004.382304882492;1007.3452256033637;1016.2936758952457;1068.6420049283577;1074.462768615692;18974.861508309546;19161.425157245307;19266.32567860174;19503.414897552528;19504.60990780148;52423.47576524186;53216.73391633172;55932.72033639969;56243.718781405834;56589.71705141448;71407.5718485617;71452.64273678599;78873;79281.20718792739;79954.20045799701;7986.440949133737;8011.83976320482;8200.26197642215;8347.499150051017;8375.371847111614;10369.859315475142;11626.360550169822;12538.373081346079;12721;12912.16070955385;11077.83383249268;11236.719082023015;11308.434578271219;11345.829812552984;11529.481173347387;10804;11107.278026928223;11284.717882052686;11302.943485282522;11353.54585816558;9916.057974492413;11303.434828258718;11409.885901140882;11505.712357191138;11967.341796201292;8045.235702608243;8130.105688374392;8158.592070396575;8362.540060296742;8370.16298370158;10610.681679549625;11018.779624407307;11135.33188008722;11180.273282236629;11190 12:16:00 INFO - PROCESS | 2517 | |3;mootools.html;1522.1704171226681;1553.9223038848058;1731.4372828830633;1836.274671504755;1864.4220291709562;8053.476524025934;8623.784405389868;8650.870236946443;9164.1213551222;9227.907720922789;8722.86915696265;8775.034746177931;8935.329850261223;8937.329700272488;8940.016598174196;2372.5254949010136;2402.9879850600723;2403.3030421177837;2408.494216214588;2412.1195763546266;2338.918137865175;2361.89371478283;2377.619580867062;2384.2847145856263;2387.3554140382084;2368.7275963264274;2380.4881950380664;2384.523095380922;2406.590879550472;2411.987940060297;2338.590746619335;2364.4679947011955;2364.9054037838637;2372.8813559321966;2378.9762102379013;2132.296342207068;2358.681577986967;2367.206985659806;2380.9642855357183;2390.796782273509;8824.602892869772;8840;8933.910660893373;8943.150400711922;8953.507557084358;5277.920831187535;5420.24116623673;5438.700871452108;5458.153986132156;5460.126379779216;2951.67531571527;3654.908627284339;3690.6862916652117;3739.4577786221116;3740.756850804689;865.8051938313891;1100.290312748281;1102.3771569063533;1114.8272017837248;1117.6311817100418;11512.942435287854;11582.305061696325;11609.6517104487;11652.650420487398;11683;4452.844150454718;4503;4575.428071491064;4588.49526552079;4627.120847039052;8567.485950842969;8854.911450885538;9223.44659320443;9263.397879137834;9268.25853931684;5230.738463076831;5257.158854583248;5285.233641122093;5299.682019078868;5312;4485.6860019798305;4835.057163853015;4920.72935988524;4958.801647934114;4962.181240095344;3442.2599141184264;3773.6981041516615;3775.697384402377;3867.632574905436;3878.5733569307354;1141.520561364229;1180.6753142885602;1192.1357016163386;1200.5977997370978;1206.3304865799566;820.8071103290722;830.5639539241826;866.6013633728429;897.9281657467387;926.9304802139867;5011.273365362075;5937.70311484424;6033.245844269466;6199.5660303779205;6224.128621992924;428.783464350501;430.23847789412565;445.98885027874564;447.58598296575536;448.84739188675354;4056.675465962716;4122.855700050483;4134.91730165401;4143.8964025899595;4165.770882601425;10601.84097238542;10718.73203169927;11022.50398732059;11053.502592383362;11056.170787190993;10740.355578665305;11061.834072488919;11105.666829995112;11115.277506962022;11155.609553665583;8086.878696819553;8307.792305192419;8312.750617481484;8455.534945578052;8484.915150848412;5477.808276710296;5960.552958528041;6049.546284028804;6095.177151084724;6114.938850611615;9388.624455021723;9727.367721098106;10195.694129176136;10262.794744104927;10411.843822342822;10219.540120694437;10342.224333175192;10382.532786024496;10559.736006599896;10609.20430967696;6608.867822643617;6922.307769223037;7143.357097861218;7150.463715221275;7213.242609526134;8502.82994340122;8522.91477085246;8549.914500854911;8577.356698247531;8587.82824343522;7442.813929651802;7493.550386976799;7639.350655194371;7717.305442510201;7723.227677232232;7374.594397308199;7421.851562968818;7429.294217049372;7507.174210836913;7536.5101268417375;7006.229314775269;7091.60996145217;7143.392811611071;7174.784756457315;7175.282471752783;4780.306855505863;4926.236433352836;4938.08645400602;4979.925301120414;5134;4725.983913458623;4729.55069268419;4785.976070119627;4795.376601041842;4892.90214195707 12:16:00 INFO - PROCESS | 2517 | |4;prototype.html;265.1938108151221;298.73711134201886;308.1771669642056;316.11330218736447;316.19528300475287;4810.470848206699;4899.824042229866;5190.662606930547;5318.282031925689;5486.61593688442;4404.603585677289;4793.616510679146;5428.67427954322;5429.728513574315;5452;4117.5882411758885;4120.526139493966;4120.855770048049;4128.979355103235;4137.290075000879;3491.2768040928117;3805.7145714071416;4130.958690413087;4131.876043718678;4158.189153115144;4022.1955608878193;4023.3361495353233;4026.1947610477873;4046.615571520701;4091.5908409158997;3675.6324367563298;4068.206699693561;4070.409790580349;4083.9994201420695;4089.652379547742;2722.782177425821;3330.400527904976;3493.109257139439;3500.94748578769;3513.982430087833;4930.9013819723405;5222.694326418396;5248.317718696583;5265.6840589564745;5272.288241087443;4323.4163387942535;4540;4693.906121877578;4722.527747225285;4740.644451666104;5544.364412498323;5692.430756924316;5707.486326230659;5726.398728133569;5811.564132690023;3364.814935178564;3793.9241215175543;3850.3839385698434;3876.2441323941844;3881.7282790204695;5378.973105134489;5914.876173527016;6091.269047714259;6106.450419462225;6109.869674110304;3816.404844705571;3946.5264168299987;3947.2105578883766;3958.524977002778;3972.503437070366;3145.4495463293833;3413.9317213655586;3597.6042635310096;3750.024993501709;4073.108093253764;4659.161350956861;4694;4694.544691145756;4699.718016918996;4756.476787553366;5426.810061647823;5811.506021988093;5853.882922341614;5867.911981320283;5915.674637894957;1103.4813637590319;1241.1932244041445;1253.3357320620087;1292.6315999939973;1344.5428554291589;801.8997625296838;821.9506829590243;831.268483734322;832.4505826154709;835.603088532942;4010.318245898204;4264.658827293809;4475.657302809209;4485.820567177277;4509.714731301563;4518.299663552155;4606.769661516911;4962.60299176065;4966.1061009018;5012.473690262543;3917.8236979335998;4384.4519435070615;4417.558244175557;4477.865664030084;4542.364069030273;2136.3377353020614;2357.504923965952;2491.364702000978;2630.973690263073;2636.3145582148395;4512.1500184924025;4863.683860549053;4914.402819083812;4958.628102892226;5284.735763211824;4419.1824512464655;4595.759145030823;4693.718376897397;5185.274061631375;5202.843914682566;4400.515943246241;4444.377802102144;4518;5207.817726379559;5279.234510995884;4446.577575130357;4451.777411129496;4466.397036400174;4575.428071491064;4698.412698412699;4430.313301438282;4698.459677137036;4937.3216953369965;4972.8756781080765;4985.177445721415;4660.953390466052;5225.790968361223;5241.27453724069;5322.627416080992;5805.680687562224;3734.6452087051684;3855.7300988930506;3868.400397938324;3872.825722842535;3913.334733095324;5433.891322173614;5799.652020878761;5807.651540907559;5835.299764028344;5926.547995741071;5310.45996660964;5585.5252303553025;5636.915446268391;5647.943520564906;5694.234787215976;4966.925496117633;5211.478852114758;5608.971955140199;5616.634918730269;5632.887342253214;5316.601254905975;5495.159121694277;5543.805966791143;5548.195511650869;5579.972100139636;4934.629902757236;5235.293235413171;5389.326334208224;5572.359178694502;5636.098224284095;4726.51857774671;5469.234307197075;5471.261379713687;5499.257600223918;5547.944520554743 12:16:00 INFO - PROCESS | 2517 | |5;yui.html;691.0532570378582;705.7847356556251;788.8719131641757;834.5993922916999;835.9791005224869;13221.405036773343;13558.779709826114;14331.140131592112;15132;15269.694606107885;11428.828567571492;12145.906868381844;14065;14074.22591757453;14080.225587592678;719.7265039285064;879.8460269452852;883.0507204754882;907.9228265597399;911.9922485653357;813.3574476163824;903.1916434790891;907.2243232036612;909.3634455880876;912.0332447605526;953.8092381523688;957.6408846682494;959.9760005999836;960.7694153403203;963.4075043848018;950.0594411532568;951.0061985225456;953.0755167487526;956.1490273656451;960.5917485068853;705.28765946394;706.2901783707392;708.3943228539576;709.2552819539515;710.6091649592703;14674.853251467455;14879.925600372037;14996.650301472811;15021.849781502264;15069.849301506954;13811.51659691916;14010.22943738094;14040.789388159186;14041.087329323562;14046.85953140476;11434.142439317084;11591;11609.354328515525;11631;11742.589009384716;3174.4603417419094;3829.636184562462;3929.4302326162833;4031.697622678311;4031.717779755418;77265;77771;78388;78845;79609;10726.474890297515;10917.890821091687;10928.071113955402;10965;11101.888981110247;21519.246826361003;21800.34598962034;21822.236221732164;21833.781662183494;21919.780802192094;7727.150013498511;7772.339351155102;7772.961135194288;7901;7989.520628762177;4364.548160875952;4409.9559004410185;4443.400140980991;4473.664475164375;4522.140793249272;3447.3450044491465;3524.22467057247;3546.0248431681493;3559.715222782171;3561.7684850485152;501.9422766381913;629.6757170057424;657.0932113683086;657.4247533408268;660.5607271164622;599.5862854630291;613.8526753579109;623.5043140703152;703.683342495879;712.0671919785098;8395.664173432995;9174.495402752778;9450.810983780424;9589.616415343307;9605.423674579548;563.3268244447927;608.656109298245;673.9022841688026;676.1413005483107;707.7699747582056;4474.351219073281;4501.504834468205;4519.209138400833;4524.728516289033;4637.582617564435;81187;84388;91095.54452227696;91819.08180918239;93681;59869.70065149647;66257.33742662608;78658;79532.01169970681;79813.81675649219;68752.62494749977;70267;70389.64805175942;71414.2858571418;71999;9667.178289845442;9699;9755.463449510346;9771.413715177112;9786.412815230824;41885.79057104695;43047;44406;44504.554954451334;44511.77744111259;58726;59352;60364.698176508835;60571;62252.066219007655;12579.433925473193;12614.306213158365;12735.172213806072;12785.29680867524;12988.350582471028;14189.503367382093;14209.147451152963;14274;14387.064840785315;14770.852291476947;12503.866300823149;12600.054995875164;12644.051696122644;12685.492580296685;12701.110922235355;12917.483300667867;13315.134516256412;13356.741851653567;13596.320183990958;13843;11046.613368532062;11081.83377249358;12811.359432028548;12976.870231297566;13586;10867.891321086689;11000.22998390105;11419.6902512053;11451.48468318911;11486.081113511067;10569.735756606146;11146.108311334912;11271.267367621078;11349.262297950607;11386.373749443861 12:16:00 INFO - PROCESS | 2517 | __end_tp_report 12:16:00 INFO - PROCESS | 2517 | __start_cc_report 12:16:00 INFO - PROCESS | 2517 | _x_x_mozilla_cycle_collect,424 12:16:00 INFO - PROCESS | 2517 | __end_cc_report 12:16:00 INFO - PROCESS | 2517 | __startTimestamp1464722160668__endTimestamp 12:16:00 INFO - PROCESS | 2517 | 12:16:00 INFO - PROCESS | 2517 | ------- Summary: start ------- 12:16:00 INFO - PROCESS | 2517 | Number of tests: 6 12:16:00 INFO - PROCESS | 2517 | 12:16:00 INFO - PROCESS | 2517 | [#0] dojo.html Cycles:165 Average:5760.63 Median:6013.91 stddev:625.76 (10.4%) stddev-sans-first:590.33 12:16:00 INFO - PROCESS | 2517 | Values: 3045.8 3480.3 3491.5 3502.5 3507.6 5384.8 5817.3 5828.7 5911.7 5922.3 5832.7 5856.9 5925.2 5980.9 5994.0 5199.4 5221.0 5225.8 5338.8 5365.7 5316.9 5349.5 5352.3 5434.0 5477.0 5157.1 5222.7 5264.7 5271.8 5310.0 5256.7 5269.5 5271.8 5284.6 5302.6 5199.9 5230.5 5241.7 5273.0 5300.9 5579.3 5830.2 5842.0 5906.5 5929.9 6099.8 6150.9 6169.6 6186.8 6207.9 6109.9 6136.7 6209.2 6222.1 6226.4 5954.1 5963.8 5974.0 5982.4 6030.4 6327.7 6343.8 6370.1 6390.1 6393.4 5618.6 5732.7 6101.0 6125.5 6137.5 6176.0 6215.6 6222.3 6223.4 6232.8 5260.9 6087.5 6114.4 6119.9 6130.1 6011.4 6102.8 6115.2 6151.7 6173.6 4977.4 4984.4 4993.8 5013.1 5024.0 4458.8 4482.6 4490.9 4508.7 4516.8 5745.3 5910.4 5948.3 5958.5 5976.6 6178.8 6182.6 6208.5 6221.2 6223.1 5970.1 5974.4 5984.1 6013.9 6041.4 5340.1 5437.5 5437.7 5455.5 5471.0 5322.4 5643.9 6305.4 6458.4 6486.4 6130.5 6212.8 6214.9 6215.8 6243.0 6304.8 6334.3 6363.0 6413.4 6444.1 5034.8 5631.2 6091.0 6143.4 6161.4 6100.9 6130.5 6173.0 6190.4 6195.2 6051.2 6070.0 6072.9 6112.8 6156.2 6084.1 6084.2 6097.0 6106.9 6107.3 4559.5 5983.3 6017.6 6079.1 6127.1 6141.1 6141.3 6144.6 6145.1 6146.1 6147.4 6156.1 6158.7 6164.8 6165.5 12:16:00 INFO - PROCESS | 2517 | 12:16:00 INFO - PROCESS | 2517 | [#1] ext.html Cycles:180 Average:65101.15 Median:38193.14 stddev:140967.33 (369.1%) stddev-sans-first:141279.35 12:16:00 INFO - PROCESS | 2517 | Values: 508.4 518.4 528.6 562.5 588.8 31401.7 38053.2 39601.8 39676.0 39695.2 40656.0 41366.0 41458.4 41465.8 41499.8 9279.4 9509.8 9531.6 9542.6 9579.9 9414.6 9469.5 9494.1 9506.1 9543.7 9316.0 9446.3 9516.9 9517.6 9520.1 9466.2 9489.0 9513.1 9519.5 9523.2 8337.5 9399.9 9406.2 9436.0 9512.3 30938.0 32624.3 32924.2 36707.8 38770.0 2759.8 3307.0 3321.0 3449.6 3483.9 30308.2 30409.0 30606.8 31330.6 31452.4 2841.0 3141.3 3168.4 3170.1 3171.6 400541.0 454235.0 456452.4 473005.0 473338.0 34696.0 34967.7 35055.0 35108.8 35265.6 35359.5 35814.8 36044.5 36124.0 37153.8 15444.1 15536.2 15552.1 15593.1 15609.5 12867.9 12902.6 12914.9 12934.5 12960.9 19693.4 20018.7 20210.9 20224.0 20257.2 6879.3 7383.5 7617.3 7996.3 8153.6 894.1 1000.1 1029.7 1059.5 1162.9 27292.6 30205.0 30323.4 30359.5 31316.9 1070.6 1105.2 1106.8 1173.2 1224.0 12358.5 15931.0 15947.0 16101.0 16426.9 582143.0 583816.0 592500.0 592583.0 595405.0 59296.4 59340.8 59600.1 59882.0 60053.7 1386.7 1391.6 1423.7 1425.9 1427.3 29490.3 30350.5 30352.0 30427.1 30474.0 51466.7 51955.7 52362.0 52364.5 52602.7 531309.0 532989.3 534716.0 534823.0 535777.0 24698.5 24783.4 24981.4 25246.9 27327.9 48518.0 48863.0 49388.8 49718.0 49918.0 39723.2 40085.4 40287.0 40748.0 40938.6 39091.6 39266.9 39451.1 39736.1 39893.8 37838.6 38333.0 38659.2 38771.6 38810.8 32891.7 34453.0 35050.6 35446.8 35913.3 23742.8 23750.4 25213.5 25544.6 25731.4 12:16:00 INFO - PROCESS | 2517 | 12:16:00 INFO - PROCESS | 2517 | [#2] jquery.html Cycles:180 Average:35332.52 Median:21823.79 stddev:103070.27 (472.3%) stddev-sans-first:103325.98 12:16:00 INFO - PROCESS | 2517 | Values: 373.2 381.9 385.4 391.1 401.6 19032.4 20685.6 21503.2 21805.3 21842.2 16032.4 18461.6 18759.7 21400.0 21438.6 5259.6 5776.7 5979.4 5993.3 5998.3 5929.0 6011.8 6014.0 6016.5 6031.2 5430.5 5839.2 5848.2 5920.3 5950.0 5879.3 5950.2 6045.7 6585.4 6605.3 5842.9 5864.8 5869.9 5881.0 5894.7 13476.1 13494.0 13498.0 13507.1 14322.0 2071.1 2117.7 2129.4 2265.9 2376.0 9701.2 10041.9 10050.9 10219.4 10419.6 1834.9 1875.4 1947.0 1953.5 1957.8 96759.0 113278.4 121852.4 122995.8 124813.0 8534.6 11387.5 11517.6 11707.2 11824.5 14407.4 14780.8 14796.5 14799.8 14842.6 8602.6 8676.0 8826.7 8920.5 8988.1 7600.5 8502.1 8811.3 8821.6 8950.1 3527.5 4561.3 4571.7 4633.9 4682.8 1718.8 1744.7 1871.5 1978.7 2004.2 844.3 906.5 969.0 984.1 1035.6 17946.6 20358.0 21357.6 21582.0 21887.8 952.7 959.5 967.2 970.1 988.5 9007.4 9652.3 10598.5 11490.2 11504.8 592999.0 610425.0 623577.0 624331.0 636086.0 121773.0 134364.0 135796.3 137440.0 138537.0 1004.4 1007.3 1016.3 1068.6 1074.5 18974.9 19161.4 19266.3 19503.4 19504.6 52423.5 53216.7 55932.7 56243.7 56589.7 71407.6 71452.6 78873.0 79281.2 79954.2 7986.4 8011.8 8200.3 8347.5 8375.4 10369.9 11626.4 12538.4 12721.0 12912.2 11077.8 11236.7 11308.4 11345.8 11529.5 10804.0 11107.3 11284.7 11302.9 11353.5 9916.1 11303.4 11409.9 11505.7 11967.3 8045.2 8130.1 8158.6 8362.5 8370.2 10610.7 11018.8 11135.3 11180.3 11190.0 12:16:00 INFO - PROCESS | 2517 | 12:16:00 INFO - PROCESS | 2517 | [#3] mootools.html Cycles:180 Average:5714.37 Median:4783.14 stddev:3246.38 (67.9%) stddev-sans-first:3240.20 12:16:00 INFO - PROCESS | 2517 | Values: 1522.2 1553.9 1731.4 1836.3 1864.4 8053.5 8623.8 8650.9 9164.1 9227.9 8722.9 8775.0 8935.3 8937.3 8940.0 2372.5 2403.0 2403.3 2408.5 2412.1 2338.9 2361.9 2377.6 2384.3 2387.4 2368.7 2380.5 2384.5 2406.6 2412.0 2338.6 2364.5 2364.9 2372.9 2379.0 2132.3 2358.7 2367.2 2381.0 2390.8 8824.6 8840.0 8933.9 8943.2 8953.5 5277.9 5420.2 5438.7 5458.2 5460.1 2951.7 3654.9 3690.7 3739.5 3740.8 865.8 1100.3 1102.4 1114.8 1117.6 11512.9 11582.3 11609.7 11652.7 11683.0 4452.8 4503.0 4575.4 4588.5 4627.1 8567.5 8854.9 9223.4 9263.4 9268.3 5230.7 5257.2 5285.2 5299.7 5312.0 4485.7 4835.1 4920.7 4958.8 4962.2 3442.3 3773.7 3775.7 3867.6 3878.6 1141.5 1180.7 1192.1 1200.6 1206.3 820.8 830.6 866.6 897.9 926.9 5011.3 5937.7 6033.2 6199.6 6224.1 428.8 430.2 446.0 447.6 448.8 4056.7 4122.9 4134.9 4143.9 4165.8 10601.8 10718.7 11022.5 11053.5 11056.2 10740.4 11061.8 11105.7 11115.3 11155.6 8086.9 8307.8 8312.8 8455.5 8484.9 5477.8 5960.6 6049.5 6095.2 6114.9 9388.6 9727.4 10195.7 10262.8 10411.8 10219.5 10342.2 10382.5 10559.7 10609.2 6608.9 6922.3 7143.4 7150.5 7213.2 8502.8 8522.9 8549.9 8577.4 8587.8 7442.8 7493.6 7639.4 7717.3 7723.2 7374.6 7421.9 7429.3 7507.2 7536.5 7006.2 7091.6 7143.4 7174.8 7175.3 4780.3 4926.2 4938.1 4979.9 5134.0 4726.0 4729.6 4786.0 4795.4 4892.9 12:16:00 INFO - PROCESS | 2517 | 12:16:00 INFO - PROCESS | 2517 | [#4] prototype.html Cycles:180 Average:4361.79 Median:4694.27 stddev:1346.39 (28.7%) stddev-sans-first:1314.59 12:16:00 INFO - PROCESS | 2517 | Values: 265.2 298.7 308.2 316.1 316.2 4810.5 4899.8 5190.7 5318.3 5486.6 4404.6 4793.6 5428.7 5429.7 5452.0 4117.6 4120.5 4120.9 4129.0 4137.3 3491.3 3805.7 4131.0 4131.9 4158.2 4022.2 4023.3 4026.2 4046.6 4091.6 3675.6 4068.2 4070.4 4084.0 4089.7 2722.8 3330.4 3493.1 3500.9 3514.0 4930.9 5222.7 5248.3 5265.7 5272.3 4323.4 4540.0 4693.9 4722.5 4740.6 5544.4 5692.4 5707.5 5726.4 5811.6 3364.8 3793.9 3850.4 3876.2 3881.7 5379.0 5914.9 6091.3 6106.5 6109.9 3816.4 3946.5 3947.2 3958.5 3972.5 3145.4 3413.9 3597.6 3750.0 4073.1 4659.2 4694.0 4694.5 4699.7 4756.5 5426.8 5811.5 5853.9 5867.9 5915.7 1103.5 1241.2 1253.3 1292.6 1344.5 801.9 822.0 831.3 832.5 835.6 4010.3 4264.7 4475.7 4485.8 4509.7 4518.3 4606.8 4962.6 4966.1 5012.5 3917.8 4384.5 4417.6 4477.9 4542.4 2136.3 2357.5 2491.4 2631.0 2636.3 4512.2 4863.7 4914.4 4958.6 5284.7 4419.2 4595.8 4693.7 5185.3 5202.8 4400.5 4444.4 4518.0 5207.8 5279.2 4446.6 4451.8 4466.4 4575.4 4698.4 4430.3 4698.5 4937.3 4972.9 4985.2 4661.0 5225.8 5241.3 5322.6 5805.7 3734.6 3855.7 3868.4 3872.8 3913.3 5433.9 5799.7 5807.7 5835.3 5926.5 5310.5 5585.5 5636.9 5647.9 5694.2 4966.9 5211.5 5609.0 5616.6 5632.9 5316.6 5495.2 5543.8 5548.2 5580.0 4934.6 5235.3 5389.3 5572.4 5636.1 4726.5 5469.2 5471.3 5499.3 5547.9 12:16:00 INFO - PROCESS | 2517 | 12:16:00 INFO - PROCESS | 2517 | [#5] yui.html Cycles:180 Average:18133.77 Median:4510.36 stddev:24216.10 (536.9%) stddev-sans-first:24248.61 12:16:00 INFO - PROCESS | 2517 | Values: 691.1 705.8 788.9 834.6 836.0 13221.4 13558.8 14331.1 15132.0 15269.7 11428.8 12145.9 14065.0 14074.2 14080.2 719.7 879.8 883.1 907.9 912.0 813.4 903.2 907.2 909.4 912.0 953.8 957.6 960.0 960.8 963.4 950.1 951.0 953.1 956.1 960.6 705.3 706.3 708.4 709.3 710.6 14674.9 14879.9 14996.7 15021.8 15069.8 13811.5 14010.2 14040.8 14041.1 14046.9 11434.1 11591.0 11609.4 11631.0 11742.6 3174.5 3829.6 3929.4 4031.7 4031.7 77265.0 77771.0 78388.0 78845.0 79609.0 10726.5 10917.9 10928.1 10965.0 11101.9 21519.2 21800.3 21822.2 21833.8 21919.8 7727.2 7772.3 7773.0 7901.0 7989.5 4364.5 4410.0 4443.4 4473.7 4522.1 3447.3 3524.2 3546.0 3559.7 3561.8 501.9 629.7 657.1 657.4 660.6 599.6 613.9 623.5 703.7 712.1 8395.7 9174.5 9450.8 9589.6 9605.4 563.3 608.7 673.9 676.1 707.8 4474.4 4501.5 4519.2 4524.7 4637.6 81187.0 84388.0 91095.5 91819.1 93681.0 59869.7 66257.3 78658.0 79532.0 79813.8 68752.6 70267.0 70389.6 71414.3 71999.0 9667.2 9699.0 9755.5 9771.4 9786.4 41885.8 43047.0 44406.0 44504.6 44511.8 58726.0 59352.0 60364.7 60571.0 62252.1 12579.4 12614.3 12735.2 12785.3 12988.4 14189.5 14209.1 14274.0 14387.1 14770.9 12503.9 12600.1 12644.1 12685.5 12701.1 12917.5 13315.1 13356.7 13596.3 13843.0 11046.6 11081.8 12811.4 12976.9 13586.0 10867.9 11000.2 11419.7 11451.5 11486.1 10569.7 11146.1 11271.3 11349.3 11386.4 12:16:00 INFO - PROCESS | 2517 | -------- Summary: end -------- 12:16:00 INFO - PROCESS | 2517 | 12:16:01 INFO - TEST-INFO | 2517: exit 0 12:16:01 INFO - TEST-OK | dromaeo_css | took 1113245ms 12:16:01 INFO - TEST-START | kraken 12:16:01 INFO - operating with platform_type : mac_ 12:16:01 INFO - Initialising browser for kraken test... 12:16:01 INFO - TEST-INFO | started process 2523 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpqeWDEd/profile http://localhost:54391/getInfo.html) 12:16:03 INFO - PROCESS | 2523 | __metrics Screen width/height:1600/1200 12:16:03 INFO - PROCESS | 2523 | colorDepth:24 12:16:03 INFO - PROCESS | 2523 | Browser inner width/height: 1024/689 12:16:03 INFO - PROCESS | 2523 | __metrics 12:16:04 ERROR - PROCESS | 2523 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 12:16:04 INFO - TEST-INFO | 2523: exit 0 12:16:04 INFO - Browser initialized. 12:16:04 INFO - Running cycle 1/1 for kraken test... 12:16:04 INFO - TEST-INFO | started process 2525 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpqeWDEd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:16:16 INFO - PROCESS | 2525 | RSS: Main: 252362752 12:16:16 INFO - PROCESS | 2525 | 12:16:19 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54391/tests/kraken/driver.html?testName=audio-beat-detection) 12:16:20 INFO - PROCESS | 2525 | RSS: Main: 265068544 12:16:20 INFO - PROCESS | 2525 | 12:16:22 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54391/tests/kraken/driver.html?testName=audio-dft) 12:16:23 INFO - PROCESS | 2525 | RSS: Main: 269164544 12:16:23 INFO - PROCESS | 2525 | 12:16:27 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54391/tests/kraken/driver.html?testName=audio-fft) 12:16:27 INFO - PROCESS | 2525 | RSS: Main: 269676544 12:16:27 INFO - PROCESS | 2525 | 12:16:29 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54391/tests/kraken/driver.html?testName=audio-oscillator) 12:16:30 INFO - PROCESS | 2525 | RSS: Main: 270544896 12:16:30 INFO - PROCESS | 2525 | 12:16:32 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54391/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:16:32 INFO - PROCESS | 2525 | RSS: Main: 271196160 12:16:32 INFO - PROCESS | 2525 | 12:16:35 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54391/tests/kraken/driver.html?testName=imaging-darkroom) 12:16:36 INFO - PROCESS | 2525 | RSS: Main: 271400960 12:16:36 INFO - PROCESS | 2525 | 12:16:39 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54391/tests/kraken/driver.html?testName=imaging-desaturate) 12:16:40 INFO - PROCESS | 2525 | RSS: Main: 271769600 12:16:40 INFO - PROCESS | 2525 | 12:16:43 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54391/tests/kraken/driver.html?testName=json-parse-financial) 12:16:43 INFO - PROCESS | 2525 | RSS: Main: 271740928 12:16:43 INFO - PROCESS | 2525 | 12:16:45 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54391/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:16:46 INFO - PROCESS | 2525 | RSS: Main: 271732736 12:16:46 INFO - PROCESS | 2525 | 12:16:48 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54391/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:16:48 INFO - PROCESS | 2525 | RSS: Main: 271896576 12:16:48 INFO - PROCESS | 2525 | 12:16:51 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54391/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:16:51 INFO - PROCESS | 2525 | RSS: Main: 272072704 12:16:51 INFO - PROCESS | 2525 | 12:16:54 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:16:55 INFO - PROCESS | 2525 | RSS: Main: 272048128 12:16:55 INFO - PROCESS | 2525 | 12:16:58 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:16:58 INFO - PROCESS | 2525 | RSS: Main: 271888384 12:16:58 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:54391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54391/tests/kraken/driver.html?testName=ai-astar) 12:17:00 INFO - PROCESS | 2525 | RSS: Main: 272977920 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | __start_tp_report 12:17:00 INFO - PROCESS | 2525 | _x_x_mozilla_page_load 12:17:00 INFO - PROCESS | 2525 | _x_x_mozilla_page_load_details 12:17:00 INFO - PROCESS | 2525 | |i|pagename|runs| 12:17:00 INFO - PROCESS | 2525 | |0;ai-astar;113;108;115;116;114;117;117;126;114;166 12:17:00 INFO - PROCESS | 2525 | |1;audio-beat-detection;155;121;149;173;123;147;138;123;174;123 12:17:00 INFO - PROCESS | 2525 | |2;audio-dft;199;201;204;245;200;212;219;285;228;206 12:17:00 INFO - PROCESS | 2525 | |3;audio-fft;70;77;77;144;58;59;58;78;144;69 12:17:00 INFO - PROCESS | 2525 | |4;audio-oscillator;69;73;122;71;71;87;108;98;102;145 12:17:00 INFO - PROCESS | 2525 | |5;imaging-gaussian-blur;92;91;86;85;132;127;93;111;104;88 12:17:00 INFO - PROCESS | 2525 | |6;imaging-darkroom;139;108;143;97;148;147;116;96;89;148 12:17:00 INFO - PROCESS | 2525 | |7;imaging-desaturate;88;92;89;90;92;90;90;93;91;93 12:17:00 INFO - PROCESS | 2525 | |8;json-parse-financial;56;59;59;59;58;58;58;59;58;58 12:17:00 INFO - PROCESS | 2525 | |9;json-stringify-tinderbox;66;61;69;99;104;108;90;76;73;74 12:17:00 INFO - PROCESS | 2525 | |10;stanford-crypto-aes;90;76;99;107;113;88;86;87;110;107 12:17:00 INFO - PROCESS | 2525 | |11;stanford-crypto-ccm;139;143;143;153;136;138;140;138;152;146 12:17:00 INFO - PROCESS | 2525 | |12;stanford-crypto-pbkdf2;149;202;163;222;227;172;170;163;166;170 12:17:00 INFO - PROCESS | 2525 | |13;stanford-crypto-sha256-iterative;68;66;64;66;72;85;67;57;73;82 12:17:00 INFO - PROCESS | 2525 | __end_tp_report 12:17:00 INFO - PROCESS | 2525 | __start_cc_report 12:17:00 INFO - PROCESS | 2525 | _x_x_mozilla_cycle_collect,1063 12:17:00 INFO - PROCESS | 2525 | __end_cc_report 12:17:00 INFO - PROCESS | 2525 | __startTimestamp1464722220797__endTimestamp 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | ------- Summary: start ------- 12:17:00 INFO - PROCESS | 2525 | Number of tests: 14 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#0] ai-astar Cycles:10 Average:120.60 Median:116.50 stddev:16.57 (14.2%) stddev-sans-first:17.35 12:17:00 INFO - PROCESS | 2525 | Values: 113.0 108.0 115.0 116.0 114.0 117.0 117.0 126.0 114.0 166.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:148.00 stddev:20.45 (13.8%) stddev-sans-first:21.19 12:17:00 INFO - PROCESS | 2525 | Values: 155.0 121.0 149.0 173.0 123.0 147.0 138.0 123.0 174.0 123.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#2] audio-dft Cycles:10 Average:219.90 Median:215.50 stddev:27.15 (12.6%) stddev-sans-first:27.72 12:17:00 INFO - PROCESS | 2525 | Values: 199.0 201.0 204.0 245.0 200.0 212.0 219.0 285.0 228.0 206.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#3] audio-fft Cycles:10 Average:83.40 Median:69.50 stddev:32.88 (47.3%) stddev-sans-first:34.51 12:17:00 INFO - PROCESS | 2525 | Values: 70.0 77.0 77.0 144.0 58.0 59.0 58.0 78.0 144.0 69.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:71.00 stddev:25.45 (35.8%) stddev-sans-first:25.25 12:17:00 INFO - PROCESS | 2525 | Values: 69.0 73.0 122.0 71.0 71.0 87.0 108.0 98.0 102.0 145.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#5] imaging-gaussian-blur Cycles:10 Average:100.90 Median:87.00 stddev:17.13 (19.7%) stddev-sans-first:17.86 12:17:00 INFO - PROCESS | 2525 | Values: 92.0 91.0 86.0 85.0 132.0 127.0 93.0 111.0 104.0 88.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#6] imaging-darkroom Cycles:10 Average:123.10 Median:148.00 stddev:24.31 (16.4%) stddev-sans-first:25.09 12:17:00 INFO - PROCESS | 2525 | Values: 139.0 108.0 143.0 97.0 148.0 147.0 116.0 96.0 89.0 148.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#7] imaging-desaturate Cycles:10 Average:90.80 Median:91.50 stddev:1.69 (1.8%) stddev-sans-first:1.45 12:17:00 INFO - PROCESS | 2525 | Values: 88.0 92.0 89.0 90.0 92.0 90.0 90.0 93.0 91.0 93.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:58.50 stddev:0.92 (1.6%) stddev-sans-first:0.53 12:17:00 INFO - PROCESS | 2525 | Values: 56.0 59.0 59.0 59.0 58.0 58.0 58.0 59.0 58.0 58.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#9] json-stringify-tinderbox Cycles:10 Average:82.00 Median:73.50 stddev:16.87 (22.9%) stddev-sans-first:16.87 12:17:00 INFO - PROCESS | 2525 | Values: 66.0 61.0 69.0 99.0 104.0 108.0 90.0 76.0 73.0 74.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#10] stanford-crypto-aes Cycles:10 Average:96.30 Median:86.50 stddev:12.54 (14.5%) stddev-sans-first:13.10 12:17:00 INFO - PROCESS | 2525 | Values: 90.0 76.0 99.0 107.0 113.0 88.0 86.0 87.0 110.0 107.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:143.00 stddev:5.90 (4.1%) stddev-sans-first:6.10 12:17:00 INFO - PROCESS | 2525 | Values: 139.0 143.0 143.0 153.0 136.0 138.0 140.0 138.0 152.0 146.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.40 Median:171.00 stddev:26.78 (15.7%) stddev-sans-first:25.88 12:17:00 INFO - PROCESS | 2525 | Values: 149.0 202.0 163.0 222.0 227.0 172.0 170.0 163.0 166.0 170.0 12:17:00 INFO - PROCESS | 2525 | 12:17:00 INFO - PROCESS | 2525 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.00 stddev:8.38 (12.0%) stddev-sans-first:8.86 12:17:00 INFO - PROCESS | 2525 | Values: 68.0 66.0 64.0 66.0 72.0 85.0 67.0 57.0 73.0 82.0 12:17:00 INFO - PROCESS | 2525 | -------- Summary: end -------- 12:17:00 INFO - PROCESS | 2525 | 12:17:01 INFO - TEST-INFO | 2525: exit 0 12:17:01 INFO - TEST-OK | kraken | took 60585ms 12:17:01 INFO - SUITE-END | took 1173s 12:17:01 INFO - Completed test suite (00:19:33) 12:17:01 INFO - javascript benchmark 12:17:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7148.576246266267, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3045.8020228685145, 3480.33873564023, 3491.4762785582166, 3502.4746288056804, 3507.6316986716392, 5384.757685904139, 5817.272840894888, 5828.708564571775, 5911.674857882815, 5922.2597175353085, 5832.650040997543, 5856.945749765051, 5925.229720136376, 5980.850478738033, 5993.970030149854, 5199.376074871021, 5220.973895130519, 5225.790968361261, 5338.839834804962, 5365.678059316453, 5316.867078323054, 5349.5185433311, 5352.277442545245, 5433.972830135844, 5477.0, 5157.149070403379, 5222.686638801646, 5264.736763161845, 5271.841844744664, 5310.0441920454305, 5256.684598924076, 5269.525742683139, 5271.841844744664, 5284.630075894689, 5302.575793936476, 5199.870003249949, 5230.503102205284, 5241.685498870042, 5273.024490469275, 5300.92048619271, 5579.274694289757, 5830.15462757902, 5841.970790146064, 5906.527477801766, 5929.851753706192, 6099.817005489843, 6150.938490615082, 6169.598976066586, 6186.81439556814, 6207.906881396782, 6109.890546867989, 6136.723847426876, 6209.161763162006, 6222.097795819581, 6226.40849119333, 5954.106883967396, 5963.821085367446, 5974.044152935509, 5982.4017598239825, 6030.427109424598, 6327.746890124436, 6343.809685709436, 6370.076338930921, 6390.137331460193, 6393.392627700361, 5618.624221013767, 5732.742026608813, 6100.97480593228, 6125.540584456184, 6137.478314343331, 6176.011838105971, 6215.595986260879, 6222.28443728977, 6223.408776166257, 6232.781852635227, 5260.8952120054455, 6087.512998960073, 6114.358196333954, 6119.938800612026, 6130.111133885562, 6011.398860114041, 6102.816915492543, 6115.235595550556, 6151.72317245725, 6173.59871608353, 4977.352944117314, 4984.42679091909, 4993.7503124843615, 5013.1227035268685, 5024.020316038411, 4458.799354029076, 4482.574155455227, 4490.865274041784, 4508.684392092522, 4516.751578663205, 5745.253117094834, 5910.379410161957, 5948.256467941507, 5958.523318134538, 5976.61152025117, 6178.814635560941, 6182.629042257389, 6208.50331973441, 6221.160143380676, 6223.097650840603, 5970.104484327298, 5974.372690867396, 5984.102384642339, 6013.909791353133, 6041.426064523863, 5340.118880384693, 5437.4562543745305, 5437.728113594305, 5455.509004189642, 5471.0, 5322.38792538863, 5643.915341269884, 6305.400986906145, 6458.38645328693, 6486.383793539606, 6130.50955923534, 6212.751489940532, 6214.9067763984485, 6215.81352559424, 6242.968785156045, 6304.84237894038, 6334.303226644974, 6363.045543168377, 6413.422791948747, 6444.13004244421, 5034.798608055637, 5631.183478395692, 6090.969545152246, 6143.354947730602, 6161.4146656067605, 6100.853682747703, 6130.50955923534, 6172.969135154295, 6190.41191086846, 6195.1636529068, 6051.243594550681, 6070.0, 6072.939270607238, 6112.816615501542, 6156.230471191101, 6084.14821924922, 6084.239470066242, 6096.969515152396, 6106.877862442815, 6107.328193898757, 4559.4756602989755, 5983.341832398346, 6017.578769486094, 6079.118527813531, 6127.142200091902, 6141.096260159365, 6141.324454309933, 6144.631322120687, 6145.139680444652, 6146.108814222002, 6147.385261473817, 6156.076588511759, 6158.722857471335, 6164.845878853064, 6165.475934545614], "value": 5723.247037000162, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.41024411682434, 518.4219595151405, 528.6431658630423, 562.5162360370082, 588.8145234251214, 31401.685983140163, 38053.23893522128, 39601.801990990076, 39676.0, 39695.206095878064, 40656.0, 41366.0, 41458.37812432808, 41465.79267103668, 41499.79250103745, 9279.350445468837, 9509.762255943588, 9531.616276221945, 9542.618295268181, 9579.904200957972, 9414.62341506339, 9469.479178645171, 9494.05059494052, 9506.096920792514, 9543.665971690993, 9316.021817709145, 9446.338756287028, 9516.904830951706, 9517.5717092731, 9520.095590918852, 9466.24270058397, 9488.952555237249, 9513.096255855682, 9519.476428796414, 9523.238140948743, 8337.499750015018, 9399.919009313948, 9406.200472959807, 9436.00921903204, 9512.28657850664, 30938.0, 32624.347513049845, 32924.189169595666, 36707.81646091752, 38770.0, 2759.7930155238237, 3306.996178142155, 3320.9501857472155, 3449.6032956210106, 3483.8954831354845, 30308.242293942607, 30409.0, 30606.846965765028, 31330.5901234445, 31452.37095258082, 2840.985795071032, 3141.277506173593, 3168.4138434389483, 3170.1282147409415, 3171.57183780189, 400540.99188512197, 454235.0, 456452.4354756443, 473005.0, 473338.0, 34696.0, 34967.65032349695, 35055.0, 35108.824455877555, 35265.64734352675, 35359.4696079559, 35814.820925895205, 36044.459333110026, 36124.0, 37153.814230929216, 15444.0733555987, 15536.223188840513, 15552.066875987477, 15593.064416135068, 15609.453669121527, 12867.871321286855, 12902.612921612366, 12914.870851291555, 12934.547290844775, 12960.935195323964, 19693.40919772409, 20018.69971950422, 20210.88840113808, 20223.988800560208, 20257.189712411633, 6879.277675844064, 7383.483156179123, 7617.286110625065, 7996.280334769898, 8153.5923203839575, 894.1148263219366, 1000.0649392817738, 1029.6910926721953, 1059.4914441068331, 1162.9069674426196, 27292.590611140848, 30205.0, 30323.393532129234, 30359.544606830914, 31316.9039083631, 1070.5878236878857, 1105.209775010855, 1106.833974903771, 1173.1609504997925, 1223.9816402753966, 12358.50565977351, 15931.044137351795, 15946.963447375883, 16101.033937963759, 16426.917865410596, 582143.0, 583816.0, 592500.0, 592583.0, 595405.0, 59296.407035929085, 59340.813183736085, 59600.10599841006, 59882.0, 60053.69973150106, 1386.729587730383, 1391.5964370332692, 1423.7294913966314, 1425.8788003019858, 1427.336288625794, 29490.262743431587, 30350.544741829333, 30352.0, 30427.087187384415, 30474.0, 51466.74266628643, 51955.74022129865, 52362.0, 52364.47635523596, 52602.73698631483, 531309.0, 532989.3350533377, 534716.0, 534823.0, 535777.0, 24698.50602987966, 24783.380415489755, 24981.37546561278, 25246.873765631055, 27327.86336068307, 48518.0, 48863.0, 49388.75305623449, 49718.00563988772, 49918.0, 39723.20553588854, 40085.398719019824, 40287.0, 40748.0, 40938.59061409467, 39091.609083909934, 39266.87697791691, 39451.05489451032, 39736.096526856265, 39893.800530997156, 37838.62161378351, 38333.041673958374, 38659.2268154641, 38771.6122838768, 38810.80594597009, 32891.67108328982, 34453.0, 35050.649493505756, 35446.822765886005, 35913.281734365686, 23742.762572374057, 23750.406239844142, 25213.495730085662, 25544.616830747924, 25731.35671608225], "value": 19256.124456759662, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.1548043681063, 381.9115520765817, 385.37569137996445, 391.06730447881796, 401.6004075944436, 19032.42902712919, 20685.586288274226, 21503.204583404175, 21805.345839624795, 21842.235521756742, 16032.43886463974, 18461.630767384646, 18759.718604220914, 21400.036998335072, 21438.57122857546, 5259.5529380002745, 5776.653400795944, 5979.402059794008, 5993.28080630325, 5998.340182579913, 5929.0, 6011.789587364443, 6014.0, 6016.548758843082, 6031.24609423822, 5430.456954304577, 5839.211706419643, 5848.181254624356, 5920.289565252176, 5950.0, 5879.323877754049, 5950.226470558821, 6045.72794224261, 6585.374389432996, 6605.306442823482, 5842.883142337172, 5864.794732184353, 5869.911951320733, 5881.000229960917, 5894.705264736746, 13476.056676032682, 13493.987950903722, 13498.0, 13507.054506184571, 14321.997460177792, 2071.099071903712, 2117.676452217364, 2129.3824790810645, 2265.920692775745, 2375.964360534593, 9701.205277023773, 10041.949790251076, 10050.9497452513, 10219.386836789816, 10419.583216671324, 1834.926602935881, 1875.4467432107424, 1947.0264867566216, 1953.540917884296, 1957.7944315846773, 96759.0, 113278.43360783225, 121852.39073804574, 122995.77004230022, 124813.0, 8534.615942282611, 11387.487563059516, 11517.596884109016, 11707.239029463057, 11824.467898944567, 14407.42370305197, 14780.778288325684, 14796.48212312564, 14799.778003329959, 14842.589953954357, 8602.612882420306, 8676.045634980148, 8826.691065812665, 8920.509371984603, 8988.10118988096, 7600.543967361976, 8502.06477287498, 8811.339149563808, 8821.558922054, 8950.104989500998, 3527.5237842891393, 4561.340442953326, 4571.725696458223, 4633.86098417048, 4682.81268749253, 1718.802337731152, 1744.7437844751757, 1871.5321169707572, 1978.6636271833822, 2004.2484068474323, 844.2697067037092, 906.5286051253312, 969.0, 984.1142971325864, 1035.5909415780725, 17946.64106717858, 20358.0, 21357.572848543252, 21582.028808703646, 21887.79617121042, 952.6998995316453, 959.5106495687111, 967.2165545907837, 970.0541971577796, 988.5403287471361, 9007.369484136048, 9652.27607929408, 10598.470076496144, 11490.195686302046, 11504.827427588592, 592999.0, 610425.0, 623577.0, 624331.0, 636086.0, 121773.0, 134364.0, 135796.3210183943, 137440.0, 138537.0, 1004.382304882492, 1007.3452256033637, 1016.2936758952457, 1068.6420049283577, 1074.462768615692, 18974.861508309546, 19161.425157245307, 19266.32567860174, 19503.414897552528, 19504.60990780148, 52423.47576524186, 53216.73391633172, 55932.72033639969, 56243.718781405834, 56589.71705141448, 71407.5718485617, 71452.64273678599, 78873.0, 79281.20718792739, 79954.20045799701, 7986.440949133737, 8011.83976320482, 8200.26197642215, 8347.499150051017, 8375.371847111614, 10369.859315475142, 11626.360550169822, 12538.373081346079, 12721.0, 12912.16070955385, 11077.83383249268, 11236.719082023015, 11308.434578271219, 11345.829812552984, 11529.481173347387, 10804.0, 11107.278026928223, 11284.717882052686, 11302.943485282522, 11353.54585816558, 9916.057974492413, 11303.434828258718, 11409.885901140882, 11505.712357191138, 11967.341796201292, 8045.235702608243, 8130.105688374392, 8158.592070396575, 8362.540060296742, 8370.16298370158, 10610.681679549625, 11018.779624407307, 11135.33188008722, 11180.273282236629, 11190.0], "value": 9453.866324785025, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.1704171226681, 1553.9223038848058, 1731.4372828830633, 1836.274671504755, 1864.4220291709562, 8053.476524025934, 8623.784405389868, 8650.870236946443, 9164.1213551222, 9227.907720922789, 8722.86915696265, 8775.034746177931, 8935.329850261223, 8937.329700272488, 8940.016598174196, 2372.5254949010136, 2402.9879850600723, 2403.3030421177837, 2408.494216214588, 2412.1195763546266, 2338.918137865175, 2361.89371478283, 2377.619580867062, 2384.2847145856263, 2387.3554140382084, 2368.7275963264274, 2380.4881950380664, 2384.523095380922, 2406.590879550472, 2411.987940060297, 2338.590746619335, 2364.4679947011955, 2364.9054037838637, 2372.8813559321966, 2378.9762102379013, 2132.296342207068, 2358.681577986967, 2367.206985659806, 2380.9642855357183, 2390.796782273509, 8824.602892869772, 8840.0, 8933.910660893373, 8943.150400711922, 8953.507557084358, 5277.920831187535, 5420.24116623673, 5438.700871452108, 5458.153986132156, 5460.126379779216, 2951.67531571527, 3654.908627284339, 3690.6862916652117, 3739.4577786221116, 3740.756850804689, 865.8051938313891, 1100.290312748281, 1102.3771569063533, 1114.8272017837248, 1117.6311817100418, 11512.942435287854, 11582.305061696325, 11609.6517104487, 11652.650420487398, 11683.0, 4452.844150454718, 4503.0, 4575.428071491064, 4588.49526552079, 4627.120847039052, 8567.485950842969, 8854.911450885538, 9223.44659320443, 9263.397879137834, 9268.25853931684, 5230.738463076831, 5257.158854583248, 5285.233641122093, 5299.682019078868, 5312.0, 4485.6860019798305, 4835.057163853015, 4920.72935988524, 4958.801647934114, 4962.181240095344, 3442.2599141184264, 3773.6981041516615, 3775.697384402377, 3867.632574905436, 3878.5733569307354, 1141.520561364229, 1180.6753142885602, 1192.1357016163386, 1200.5977997370978, 1206.3304865799566, 820.8071103290722, 830.5639539241826, 866.6013633728429, 897.9281657467387, 926.9304802139867, 5011.273365362075, 5937.70311484424, 6033.245844269466, 6199.5660303779205, 6224.128621992924, 428.783464350501, 430.23847789412565, 445.98885027874564, 447.58598296575536, 448.84739188675354, 4056.675465962716, 4122.855700050483, 4134.91730165401, 4143.8964025899595, 4165.770882601425, 10601.84097238542, 10718.73203169927, 11022.50398732059, 11053.502592383362, 11056.170787190993, 10740.355578665305, 11061.834072488919, 11105.666829995112, 11115.277506962022, 11155.609553665583, 8086.878696819553, 8307.792305192419, 8312.750617481484, 8455.534945578052, 8484.915150848412, 5477.808276710296, 5960.552958528041, 6049.546284028804, 6095.177151084724, 6114.938850611615, 9388.624455021723, 9727.367721098106, 10195.694129176136, 10262.794744104927, 10411.843822342822, 10219.540120694437, 10342.224333175192, 10382.532786024496, 10559.736006599896, 10609.20430967696, 6608.867822643617, 6922.307769223037, 7143.357097861218, 7150.463715221275, 7213.242609526134, 8502.82994340122, 8522.91477085246, 8549.914500854911, 8577.356698247531, 8587.82824343522, 7442.813929651802, 7493.550386976799, 7639.350655194371, 7717.305442510201, 7723.227677232232, 7374.594397308199, 7421.851562968818, 7429.294217049372, 7507.174210836913, 7536.5101268417375, 7006.229314775269, 7091.60996145217, 7143.392811611071, 7174.784756457315, 7175.282471752783, 4780.306855505863, 4926.236433352836, 4938.08645400602, 4979.925301120414, 5134.0, 4725.983913458623, 4729.55069268419, 4785.976070119627, 4795.376601041842, 4892.90214195707], "value": 4496.719486283836, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.1938108151221, 298.73711134201886, 308.1771669642056, 316.11330218736447, 316.19528300475287, 4810.470848206699, 4899.824042229866, 5190.662606930547, 5318.282031925689, 5486.61593688442, 4404.603585677289, 4793.616510679146, 5428.67427954322, 5429.728513574315, 5452.0, 4117.5882411758885, 4120.526139493966, 4120.855770048049, 4128.979355103235, 4137.290075000879, 3491.2768040928117, 3805.7145714071416, 4130.958690413087, 4131.876043718678, 4158.189153115144, 4022.1955608878193, 4023.3361495353233, 4026.1947610477873, 4046.615571520701, 4091.5908409158997, 3675.6324367563298, 4068.206699693561, 4070.409790580349, 4083.9994201420695, 4089.652379547742, 2722.782177425821, 3330.400527904976, 3493.109257139439, 3500.94748578769, 3513.982430087833, 4930.9013819723405, 5222.694326418396, 5248.317718696583, 5265.6840589564745, 5272.288241087443, 4323.4163387942535, 4540.0, 4693.906121877578, 4722.527747225285, 4740.644451666104, 5544.364412498323, 5692.430756924316, 5707.486326230659, 5726.398728133569, 5811.564132690023, 3364.814935178564, 3793.9241215175543, 3850.3839385698434, 3876.2441323941844, 3881.7282790204695, 5378.973105134489, 5914.876173527016, 6091.269047714259, 6106.450419462225, 6109.869674110304, 3816.404844705571, 3946.5264168299987, 3947.2105578883766, 3958.524977002778, 3972.503437070366, 3145.4495463293833, 3413.9317213655586, 3597.6042635310096, 3750.024993501709, 4073.108093253764, 4659.161350956861, 4694.0, 4694.544691145756, 4699.718016918996, 4756.476787553366, 5426.810061647823, 5811.506021988093, 5853.882922341614, 5867.911981320283, 5915.674637894957, 1103.4813637590319, 1241.1932244041445, 1253.3357320620087, 1292.6315999939973, 1344.5428554291589, 801.8997625296838, 821.9506829590243, 831.268483734322, 832.4505826154709, 835.603088532942, 4010.318245898204, 4264.658827293809, 4475.657302809209, 4485.820567177277, 4509.714731301563, 4518.299663552155, 4606.769661516911, 4962.60299176065, 4966.1061009018, 5012.473690262543, 3917.8236979335998, 4384.4519435070615, 4417.558244175557, 4477.865664030084, 4542.364069030273, 2136.3377353020614, 2357.504923965952, 2491.364702000978, 2630.973690263073, 2636.3145582148395, 4512.1500184924025, 4863.683860549053, 4914.402819083812, 4958.628102892226, 5284.735763211824, 4419.1824512464655, 4595.759145030823, 4693.718376897397, 5185.274061631375, 5202.843914682566, 4400.515943246241, 4444.377802102144, 4518.0, 5207.817726379559, 5279.234510995884, 4446.577575130357, 4451.777411129496, 4466.397036400174, 4575.428071491064, 4698.412698412699, 4430.313301438282, 4698.459677137036, 4937.3216953369965, 4972.8756781080765, 4985.177445721415, 4660.953390466052, 5225.790968361223, 5241.27453724069, 5322.627416080992, 5805.680687562224, 3734.6452087051684, 3855.7300988930506, 3868.400397938324, 3872.825722842535, 3913.334733095324, 5433.891322173614, 5799.652020878761, 5807.651540907559, 5835.299764028344, 5926.547995741071, 5310.45996660964, 5585.5252303553025, 5636.915446268391, 5647.943520564906, 5694.234787215976, 4966.925496117633, 5211.478852114758, 5608.971955140199, 5616.634918730269, 5632.887342253214, 5316.601254905975, 5495.159121694277, 5543.805966791143, 5548.195511650869, 5579.972100139636, 4934.629902757236, 5235.293235413171, 5389.326334208224, 5572.359178694502, 5636.098224284095, 4726.51857774671, 5469.234307197075, 5471.261379713687, 5499.257600223918, 5547.944520554743], "value": 3929.216003443857, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.0532570378582, 705.7847356556251, 788.8719131641757, 834.5993922916999, 835.9791005224869, 13221.405036773343, 13558.779709826114, 14331.140131592112, 15132.0, 15269.694606107885, 11428.828567571492, 12145.906868381844, 14065.0, 14074.22591757453, 14080.225587592678, 719.7265039285064, 879.8460269452852, 883.0507204754882, 907.9228265597399, 911.9922485653357, 813.3574476163824, 903.1916434790891, 907.2243232036612, 909.3634455880876, 912.0332447605526, 953.8092381523688, 957.6408846682494, 959.9760005999836, 960.7694153403203, 963.4075043848018, 950.0594411532568, 951.0061985225456, 953.0755167487526, 956.1490273656451, 960.5917485068853, 705.28765946394, 706.2901783707392, 708.3943228539576, 709.2552819539515, 710.6091649592703, 14674.853251467455, 14879.925600372037, 14996.650301472811, 15021.849781502264, 15069.849301506954, 13811.51659691916, 14010.22943738094, 14040.789388159186, 14041.087329323562, 14046.85953140476, 11434.142439317084, 11591.0, 11609.354328515525, 11631.0, 11742.589009384716, 3174.4603417419094, 3829.636184562462, 3929.4302326162833, 4031.697622678311, 4031.717779755418, 77265.0, 77771.0, 78388.0, 78845.0, 79609.0, 10726.474890297515, 10917.890821091687, 10928.071113955402, 10965.0, 11101.888981110247, 21519.246826361003, 21800.34598962034, 21822.236221732164, 21833.781662183494, 21919.780802192094, 7727.150013498511, 7772.339351155102, 7772.961135194288, 7901.0, 7989.520628762177, 4364.548160875952, 4409.9559004410185, 4443.400140980991, 4473.664475164375, 4522.140793249272, 3447.3450044491465, 3524.22467057247, 3546.0248431681493, 3559.715222782171, 3561.7684850485152, 501.9422766381913, 629.6757170057424, 657.0932113683086, 657.4247533408268, 660.5607271164622, 599.5862854630291, 613.8526753579109, 623.5043140703152, 703.683342495879, 712.0671919785098, 8395.664173432995, 9174.495402752778, 9450.810983780424, 9589.616415343307, 9605.423674579548, 563.3268244447927, 608.656109298245, 673.9022841688026, 676.1413005483107, 707.7699747582056, 4474.351219073281, 4501.504834468205, 4519.209138400833, 4524.728516289033, 4637.582617564435, 81187.0, 84388.0, 91095.54452227696, 91819.08180918239, 93681.0, 59869.70065149647, 66257.33742662608, 78658.0, 79532.01169970681, 79813.81675649219, 68752.62494749977, 70267.0, 70389.64805175942, 71414.2858571418, 71999.0, 9667.178289845442, 9699.0, 9755.463449510346, 9771.413715177112, 9786.412815230824, 41885.79057104695, 43047.0, 44406.0, 44504.554954451334, 44511.77744111259, 58726.0, 59352.0, 60364.698176508835, 60571.0, 62252.066219007655, 12579.433925473193, 12614.306213158365, 12735.172213806072, 12785.29680867524, 12988.350582471028, 14189.503367382093, 14209.147451152963, 14274.0, 14387.064840785315, 14770.852291476947, 12503.866300823149, 12600.054995875164, 12644.051696122644, 12685.492580296685, 12701.110922235355, 12917.483300667867, 13315.134516256412, 13356.741851653567, 13596.320183990958, 13843.0, 11046.613368532062, 11081.83377249358, 12811.359432028548, 12976.870231297566, 13586.0, 10867.891321086689, 11000.22998390105, 11419.6902512053, 11451.48468318911, 11486.081113511067, 10569.735756606146, 11146.108311334912, 11271.267367621078, 11349.262297950607, 11386.373749443861], "value": 7248.496229298893, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 115.0, 116.0, 114.0, 117.0, 117.0, 126.0, 114.0, 166.0], "value": 120.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 149.0, 173.0, 123.0, 147.0, 138.0, 123.0, 174.0, 123.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 204.0, 245.0, 200.0, 212.0, 219.0, 285.0, 228.0, 206.0], "value": 219.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 77.0, 144.0, 58.0, 59.0, 58.0, 78.0, 144.0, 69.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 122.0, 71.0, 71.0, 87.0, 108.0, 98.0, 102.0, 145.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 86.0, 85.0, 132.0, 127.0, 93.0, 111.0, 104.0, 88.0], "value": 100.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 108.0, 143.0, 97.0, 148.0, 147.0, 116.0, 96.0, 89.0, 148.0], "value": 123.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 89.0, 90.0, 92.0, 90.0, 90.0, 93.0, 91.0, 93.0], "value": 90.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 59.0, 59.0, 58.0, 58.0, 58.0, 59.0, 58.0, 58.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 61.0, 69.0, 99.0, 104.0, 108.0, 90.0, 76.0, 73.0, 74.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 76.0, 99.0, 107.0, 113.0, 88.0, 86.0, 87.0, 110.0, 107.0], "value": 96.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 143.0, 153.0, 136.0, 138.0, 140.0, 138.0, 152.0, 146.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 202.0, 163.0, 222.0, 227.0, 172.0, 170.0, 163.0, 166.0, 170.0], "value": 180.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 64.0, 66.0, 72.0, 85.0, 67.0, 57.0, 73.0, 82.0], "value": 70.0, "unit": "score"}]}]} 12:17:02 INFO - Return code: 0 12:17:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:17:02 ERROR - # TBPL SUCCESS # 12:17:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:17:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:17:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:17:02 INFO - 2016-05-31 12:17:02.088 screenresolution[2530:25641] starting screenresolution argv=screenresolution get 12:17:02 INFO - 2016-05-31 12:17:02.091 screenresolution[2530:25641] Display 0: 1600x1200x32@60 12:17:02 INFO - 2016-05-31 12:17:02.097 screenresolution[2531:25644] starting screenresolution argv=screenresolution list 12:17:02 INFO - Available Modes on Display 0 12:17:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:17:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:17:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:17:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:17:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:17:02 INFO - Intel Iris: 12:17:02 INFO - Chipset Model: Intel Iris 12:17:02 INFO - Type: GPU 12:17:02 INFO - Bus: Built-In 12:17:02 INFO - VRAM (Dynamic, Max): 1536 MB 12:17:02 INFO - Vendor: Intel (0x8086) 12:17:02 INFO - Device ID: 0x0a2e 12:17:02 INFO - Revision ID: 0x0009 12:17:02 INFO - Displays: 12:17:02 INFO - Display: 12:17:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:17:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:17:02 INFO - Display Serial Number: 1600x1200 60 12:17:02 INFO - Main Display: Yes 12:17:02 INFO - Mirror: Off 12:17:02 INFO - Online: Yes 12:17:02 INFO - Rotation: Supported 12:17:02 INFO - Return code: 0 12:17:02 INFO - Running post-action listener: _resource_record_post_action 12:17:02 INFO - ##### 12:17:02 INFO - ##### Finished run-tests step (success) 12:17:02 INFO - ##### 12:17:02 INFO - Running post-run listener: _resource_record_post_run 12:17:02 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 409600; Write bytes: 701925888; Read time: 5; Write time: 2057 12:17:02 INFO - TinderboxPrint: CPU usage
54.8% 12:17:02 INFO - TinderboxPrint: I/O read bytes / time
409,600 / 5 12:17:02 INFO - TinderboxPrint: I/O write bytes / time
701,925,888 / 2,057 12:17:02 INFO - TinderboxPrint: CPU idle
2,187.7 (45.2%) 12:17:02 INFO - TinderboxPrint: CPU system
978.5 (20.2%) 12:17:02 INFO - TinderboxPrint: CPU user
1,675.6 (34.6%) 12:17:02 INFO - TinderboxPrint: Swap in / out
399,187,968 / 0 12:17:02 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 390832640; Write bytes: 385800704; Read time: 29408; Write time: 716 12:17:02 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 409600; Write bytes: 298573824; Read time: 5; Write time: 1301 12:17:02 INFO - Running post-run listener: _upload_blobber_files 12:17:02 INFO - Blob upload gear active. 12:17:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:17:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:17:02 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'] 12:17:02 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 12:17:03 INFO - (blobuploader) - INFO - Open directory for files ... 12:17:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:17:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:17:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:04 INFO - (blobuploader) - INFO - Done attempting. 12:17:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:17:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:17:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:05 INFO - (blobuploader) - INFO - Done attempting. 12:17:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:17:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:17:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:07 INFO - (blobuploader) - INFO - Done attempting. 12:17:07 INFO - (blobuploader) - INFO - Iteration through files over. 12:17:07 INFO - Return code: 0 12:17:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:17:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:17:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f0641d8e523858f729658f7e6a6377ba0112d0df53b0a356bc1fb7b2c811dc0ac09720ca624db06ecbeb29aeddedf740cb140050e6eecdb5937827442c65765b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1994a1557345692b3bb4b51b640b6022bc139120a363fe5b336f5d50f46fec3b69b2a6f7fe2f8857844a5c40f7ad90470f89ccd86ebadb37caf1a169fae07c77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b3f7a457ba6d4f9ec86c16ed6952e0a414ad810ca4807a34a7c31855914480edd629ffa4b74e86a38430c067a1b9b20d82260c77406ee1cc5ec701f7d81c762a"} 12:17:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:17:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:17:07 INFO - Contents: 12:17:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f0641d8e523858f729658f7e6a6377ba0112d0df53b0a356bc1fb7b2c811dc0ac09720ca624db06ecbeb29aeddedf740cb140050e6eecdb5937827442c65765b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1994a1557345692b3bb4b51b640b6022bc139120a363fe5b336f5d50f46fec3b69b2a6f7fe2f8857844a5c40f7ad90470f89ccd86ebadb37caf1a169fae07c77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b3f7a457ba6d4f9ec86c16ed6952e0a414ad810ca4807a34a7c31855914480edd629ffa4b74e86a38430c067a1b9b20d82260c77406ee1cc5ec701f7d81c762a"} 12:17:07 INFO - Running post-run listener: copy_logs_to_upload_dir 12:17:07 INFO - Copying logs to upload dir... 12:17:07 INFO - mkdir: /builds/slave/test/build/upload/logs 12:17:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.331386 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-05-31 12:17:07.708693) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 12:17:07.712151) ========= 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.OMb5AssGKB/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.3xlYubOw3S/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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/f0641d8e523858f729658f7e6a6377ba0112d0df53b0a356bc1fb7b2c811dc0ac09720ca624db06ecbeb29aeddedf740cb140050e6eecdb5937827442c65765b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1994a1557345692b3bb4b51b640b6022bc139120a363fe5b336f5d50f46fec3b69b2a6f7fe2f8857844a5c40f7ad90470f89ccd86ebadb37caf1a169fae07c77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b3f7a457ba6d4f9ec86c16ed6952e0a414ad810ca4807a34a7c31855914480edd629ffa4b74e86a38430c067a1b9b20d82260c77406ee1cc5ec701f7d81c762a"} build_url:https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011486 build_url: 'https://queue.taskcluster.net/v1/task/KaTxJ8xpTqynagAr-uVRcg/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/f0641d8e523858f729658f7e6a6377ba0112d0df53b0a356bc1fb7b2c811dc0ac09720ca624db06ecbeb29aeddedf740cb140050e6eecdb5937827442c65765b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1994a1557345692b3bb4b51b640b6022bc139120a363fe5b336f5d50f46fec3b69b2a6f7fe2f8857844a5c40f7ad90470f89ccd86ebadb37caf1a169fae07c77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b3f7a457ba6d4f9ec86c16ed6952e0a414ad810ca4807a34a7c31855914480edd629ffa4b74e86a38430c067a1b9b20d82260c77406ee1cc5ec701f7d81c762a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 12:17:07.742797) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 12:17:07.743112) ========= 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.OMb5AssGKB/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.3xlYubOw3S/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005139 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 12:17:07.791589) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 12:17:07.791909) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 12:17:07.792254) ========= ========= Total master_lag: 0.17 =========