builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0200 starttime: 1464740026.34 results: success (0) buildid: 20160531141745 builduid: b1ae79fd489845b78b4da3959e773d45 revision: d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:46.341458) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:46.342526) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:46.343006) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:46.554252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:46.554517) ========= 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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 17:13:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.7M=0.001s 2016-05-31 17:13:47 (15.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.547798 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:47.131015) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:47.131304) ========= 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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.027566 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:47.182146) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:47.182694) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 --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 d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 17:13:47,242 truncating revision to first 12 chars 2016-05-31 17:13:47,243 Setting DEBUG logging. 2016-05-31 17:13:47,243 attempt 1/10 2016-05-31 17:13:47,243 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d39456c7ccf1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 17:13:47,567 unpacking tar archive at: fx-team-d39456c7ccf1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.582686 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:47.784668) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:47.784953) ========= script_repo_revision: d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:47.785348) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:47.785618) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 17:13:47.801721) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-31 17:13:47.801999) ========= /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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:13:47 INFO - MultiFileLogger online at 20160531 17:13:47 in /builds/slave/test 17:13: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 17:13:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:13:47 INFO - {'append_to_log': False, 17:13:47 INFO - 'base_work_dir': '/builds/slave/test', 17:13:47 INFO - 'blob_upload_branch': 'Fx-Team', 17:13:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:13:47 INFO - 'branch': 'Fx-Team', 17:13:47 INFO - 'buildbot_json_path': 'buildprops.json', 17:13:47 INFO - 'config_files': ('talos/mac_config.py',), 17:13:47 INFO - 'default_actions': ('clobber', 17:13:47 INFO - 'read-buildbot-config', 17:13:47 INFO - 'download-and-extract', 17:13:47 INFO - 'populate-webroot', 17:13:47 INFO - 'create-virtualenv', 17:13:47 INFO - 'install', 17:13:47 INFO - 'run-tests'), 17:13:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:13:47 INFO - 'download_minidump_stackwalk': True, 17:13:47 INFO - 'download_symbols': 'ondemand', 17:13:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:13:47 INFO - 'tooltool.py': '/tools/tooltool.py', 17:13:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:13:47 INFO - '/tools/misc-python/virtualenv.py')}, 17:13:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:13:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:13:47 INFO - 'installer_path': 'installer.exe', 17:13:47 INFO - 'log_level': 'info', 17:13:47 INFO - 'log_name': 'talos', 17:13:47 INFO - 'log_to_console': True, 17:13:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:13:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:13:47 INFO - 'opt_config_files': (), 17:13:47 INFO - 'pip_index': False, 17:13:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:13:47 INFO - 'cmd': ('bash', 17:13:47 INFO - '-c', 17:13:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:13:47 INFO - 'enabled': True, 17:13:47 INFO - 'halt_on_failure': False, 17:13:47 INFO - 'name': 'check_screen_resolution'},), 17:13:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:13:47 INFO - 'cmd': ('bash', 17:13:47 INFO - '-c', 17:13:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:13:47 INFO - 'enabled': True, 17:13:47 INFO - 'halt_on_failure': False, 17:13:47 INFO - 'name': 'check_screen_resolution'},), 17:13:47 INFO - 'run_cmd_checks_enabled': True, 17:13:47 INFO - 'sps_profile': False, 17:13:47 INFO - 'sps_profile_interval': 0, 17:13:47 INFO - 'suite': 'dromaeojs-e10s', 17:13:47 INFO - 'system_bits': '32', 17:13:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:13:47 INFO - 'title': 't-yosemite-r7-0200', 17:13:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:13:47 INFO - 'use_talos_json': True, 17:13:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:13:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:13:47 INFO - 'work_dir': 'build'} 17:13:47 INFO - ##### 17:13:47 INFO - ##### Running clobber step. 17:13:47 INFO - ##### 17:13:47 INFO - Running pre-action listener: _resource_record_pre_action 17:13:47 INFO - Running main action method: clobber 17:13:47 INFO - rmtree: /builds/slave/test/build 17:13:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:13:49 INFO - Running post-action listener: _resource_record_post_action 17:13:49 INFO - ##### 17:13:49 INFO - ##### Finished clobber step (success) 17:13:49 INFO - ##### 17:13:49 INFO - ##### 17:13:49 INFO - ##### Running read-buildbot-config step. 17:13:49 INFO - ##### 17:13:49 INFO - Running pre-action listener: _resource_record_pre_action 17:13:49 INFO - Running main action method: read_buildbot_config 17:13:49 INFO - Using buildbot properties: 17:13:49 INFO - { 17:13:49 INFO - "project": "", 17:13:49 INFO - "product": "firefox", 17:13:49 INFO - "script_repo_revision": "production", 17:13:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 17:13:49 INFO - "repository": "", 17:13:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 17:13:49 INFO - "buildid": "20160531141745", 17:13:49 INFO - "pgo_build": "False", 17:13:49 INFO - "basedir": "/builds/slave/test", 17:13:49 INFO - "buildnumber": 9, 17:13:49 INFO - "slavename": "t-yosemite-r7-0200", 17:13:49 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:13:49 INFO - "platform": "yosemite_r7", 17:13:49 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 17:13:49 INFO - "branch": "fx-team", 17:13:49 INFO - "repo_path": "integration/fx-team", 17:13:49 INFO - "revision": "d39456c7ccf19d98bb20c3de1dd88cb3c9513c55", 17:13:49 INFO - "stage_platform": "macosx64", 17:13:49 INFO - "builduid": "b1ae79fd489845b78b4da3959e773d45", 17:13:49 INFO - "slavebuilddir": "test" 17:13:49 INFO - } 17:13:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 17:13:49 INFO - Running post-action listener: _resource_record_post_action 17:13:49 INFO - ##### 17:13:49 INFO - ##### Finished read-buildbot-config step (success) 17:13:49 INFO - ##### 17:13:49 INFO - ##### 17:13:49 INFO - ##### Running download-and-extract step. 17:13:49 INFO - ##### 17:13:49 INFO - Running pre-action listener: _resource_record_pre_action 17:13:49 INFO - Running main action method: download_and_extract 17:13:49 INFO - mkdir: /builds/slave/test/build/tests 17:13: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')]} 17:13:49 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:13:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:13:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:13:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:13:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 17:13:58 INFO - Downloaded 1199 bytes. 17:13:58 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:13:58 INFO - Using the following test package requirements: 17:13:58 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:13:58 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:13:58 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 17:13:58 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:13:58 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 17:13:58 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:13:58 INFO - u'jsshell-mac.zip'], 17:13:58 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:13:58 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 17:13:58 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:13:58 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:13:58 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 17:13:58 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:13:58 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 17:13:58 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:13:58 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 17:13:58 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:13:58 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 17:13:58 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:58 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:13:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:13:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:13:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:13:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:14:00 INFO - Downloaded 18687184 bytes. 17:14:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:14:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:14:03 INFO - Return code: 0 17:14:03 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:03 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:14:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:14:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:14:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:14:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:14:04 INFO - Downloaded 18687184 bytes. 17:14:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:14:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:14:08 INFO - Return code: 0 17:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:08 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:14:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:14:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:14:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:14:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:14:09 INFO - Downloaded 10834055 bytes. 17:14:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:14:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:14:09 INFO - Return code: 0 17:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:09 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:14:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:14:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:14:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 17:14:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 17:14:13 INFO - Downloaded 110990524 bytes. 17:14:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:14:13 INFO - mkdir: /builds/slave/test/properties 17:14:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:14:13 INFO - Writing to file /builds/slave/test/properties/build_url 17:14:13 INFO - Contents: 17:14:13 INFO - build_url:https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:14:15 INFO - Running post-action listener: _resource_record_post_action 17:14:15 INFO - Running post-action listener: set_extra_try_arguments 17:14:15 INFO - ##### 17:14:15 INFO - ##### Finished download-and-extract step (success) 17:14:15 INFO - ##### 17:14:15 INFO - ##### 17:14:15 INFO - ##### Running populate-webroot step. 17:14:15 INFO - ##### 17:14:15 INFO - Running pre-action listener: _resource_record_pre_action 17:14:15 INFO - Running main action method: populate_webroot 17:14:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:14:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:14:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:14:15 INFO - '--tppagecycles', 17:14:15 INFO - '7'], 17:14:15 INFO - 'tests': ['tsvgm']}}, 17:14:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:14:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:14:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:14:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:14:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:15 INFO - 'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:15 INFO - 'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['damp', 'tps']}, 17:14:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:15 INFO - 'tests': ['damp', 'tps']}, 17:14:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['dromaeo_dom']}, 17:14:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:14:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['a11yr', 17:14:15 INFO - 'ts_paint', 17:14:15 INFO - 'tpaint', 17:14:15 INFO - 'sessionrestore', 17:14:15 INFO - 'sessionrestore_no_auto_restore', 17:14:15 INFO - 'tabpaint']}, 17:14:15 INFO - 'other-e10s': {'tests': ['a11yr', 17:14:15 INFO - 'ts_paint', 17:14:15 INFO - 'tpaint', 17:14:15 INFO - 'sessionrestore', 17:14:15 INFO - 'sessionrestore_no_auto_restore', 17:14:15 INFO - 'tabpaint']}, 17:14:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:14:15 INFO - 'ts_paint', 17:14:15 INFO - 'tpaint', 17:14:15 INFO - 'sessionrestore', 17:14:15 INFO - 'sessionrestore_no_auto_restore', 17:14:15 INFO - 'tabpaint']}, 17:14:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:14:15 INFO - 'ts_paint', 17:14:15 INFO - 'tpaint', 17:14:15 INFO - 'sessionrestore', 17:14:15 INFO - 'sessionrestore_no_auto_restore', 17:14:15 INFO - 'tabpaint']}, 17:14:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['a11yr', 17:14:15 INFO - 'ts_paint', 17:14:15 INFO - 'tpaint', 17:14:15 INFO - 'sessionrestore', 17:14:15 INFO - 'sessionrestore_no_auto_restore', 17:14:15 INFO - 'tabpaint']}, 17:14:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['a11yr', 17:14:15 INFO - 'ts_paint', 17:14:15 INFO - 'tpaint', 17:14:15 INFO - 'sessionrestore', 17:14:15 INFO - 'sessionrestore_no_auto_restore', 17:14:15 INFO - 'tabpaint']}, 17:14:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['tsvgx', 17:14:15 INFO - 'tsvgr_opacity', 17:14:15 INFO - 'tart', 17:14:15 INFO - 'tscrollx', 17:14:15 INFO - 'cart']}, 17:14:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:14:15 INFO - 'tsvgr_opacity', 17:14:15 INFO - 'tart', 17:14:15 INFO - 'tscrollx', 17:14:15 INFO - 'cart']}, 17:14:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:15 INFO - 'talos_options': ['--disable-e10s'], 17:14:15 INFO - 'tests': ['tp5o']}, 17:14:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:15 INFO - 'tests': ['tp5o']}, 17:14:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:15 INFO - 'talos_options': ['--disable-e10s', 17:14:15 INFO - '--xperf_path', 17:14:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:15 INFO - 'tests': ['tp5n']}, 17:14:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:14:15 INFO - 'talos_options': ['--xperf_path', 17:14:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:15 INFO - 'tests': ['tp5n']}}, 17:14:15 INFO - 'talos.zip': {'path': '', 17:14:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:14:15 INFO - Running post-action listener: _resource_record_post_action 17:14:15 INFO - ##### 17:14:15 INFO - ##### Finished populate-webroot step (success) 17:14:15 INFO - ##### 17:14:15 INFO - ##### 17:14:15 INFO - ##### Running create-virtualenv step. 17:14:15 INFO - ##### 17:14:15 INFO - Running pre-action listener: _resource_record_pre_action 17:14:15 INFO - Running main action method: create_virtualenv 17:14:15 INFO - Creating virtualenv /builds/slave/test/build/venv 17:14:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:14:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:14:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:14:15 INFO - Using real prefix '/tools/python27' 17:14:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:14:16 INFO - Installing distribute.............................................................................................................................................................................................done. 17:14:19 INFO - Installing pip.................done. 17:14:19 INFO - Return code: 0 17:14:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c947a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c94d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0b46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, '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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:14:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:14:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:19 INFO - 'HOME': '/Users/cltbld', 17:14:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:19 INFO - 'LOGNAME': 'cltbld', 17:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:19 INFO - 'MOZ_NO_REMOTE': '1', 17:14:19 INFO - 'NO_EM_RESTART': '1', 17:14:19 INFO - 'PAGER': '/bin/cat', 17:14:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:19 INFO - 'PWD': '/builds/slave/test', 17:14:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:19 INFO - 'SHELL': '/bin/bash', 17:14:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:14:19 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:14:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:19 INFO - 'USER': 'cltbld', 17:14:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:19 INFO - 'XPC_FLAGS': '0x0', 17:14:19 INFO - 'XPC_SERVICE_NAME': '0', 17:14:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:14:19 INFO - Cleaning up... 17:14:19 INFO - Return code: 0 17:14:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c947a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c94d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0b46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, '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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:14:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:14:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:19 INFO - 'HOME': '/Users/cltbld', 17:14:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:19 INFO - 'LOGNAME': 'cltbld', 17:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:19 INFO - 'MOZ_NO_REMOTE': '1', 17:14:19 INFO - 'NO_EM_RESTART': '1', 17:14:19 INFO - 'PAGER': '/bin/cat', 17:14:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:19 INFO - 'PWD': '/builds/slave/test', 17:14:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:19 INFO - 'SHELL': '/bin/bash', 17:14:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:14:19 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:14:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:19 INFO - 'USER': 'cltbld', 17:14:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:19 INFO - 'XPC_FLAGS': '0x0', 17:14:19 INFO - 'XPC_SERVICE_NAME': '0', 17:14:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:19 INFO - Downloading/unpacking psutil>=3.1.1 17:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:14:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:22 INFO - Installing collected packages: psutil 17:14:22 INFO - Running setup.py install for psutil 17:14:23 INFO - building 'psutil._psutil_osx' extension 17:14:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:14:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:14:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:14:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:14:23 INFO - building 'psutil._psutil_posix' extension 17:14:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:14:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:14:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:14:23 INFO - ^ 17:14:23 INFO - 1 warning generated. 17:14:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:14:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:24 INFO - Successfully installed psutil 17:14:24 INFO - Cleaning up... 17:14:24 INFO - Return code: 0 17:14:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:24 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 0x10c947a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c94d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0b46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, '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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:14:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:14:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:14:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:24 INFO - 'HOME': '/Users/cltbld', 17:14:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:24 INFO - 'LOGNAME': 'cltbld', 17:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:24 INFO - 'MOZ_NO_REMOTE': '1', 17:14:24 INFO - 'NO_EM_RESTART': '1', 17:14:24 INFO - 'PAGER': '/bin/cat', 17:14:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:24 INFO - 'PWD': '/builds/slave/test', 17:14:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:24 INFO - 'SHELL': '/bin/bash', 17:14:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:14:24 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:14:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:24 INFO - 'USER': 'cltbld', 17:14:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:24 INFO - 'XPC_FLAGS': '0x0', 17:14:24 INFO - 'XPC_SERVICE_NAME': '0', 17:14:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:14:26 INFO - Installing collected packages: mozsystemmonitor 17:14:26 INFO - Running setup.py install for mozsystemmonitor 17:14:27 INFO - Successfully installed mozsystemmonitor 17:14:27 INFO - Cleaning up... 17:14:27 INFO - Return code: 0 17:14:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:27 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 0x10c947a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c94d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0b46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, '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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:14:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:14:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:27 INFO - 'HOME': '/Users/cltbld', 17:14:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:27 INFO - 'LOGNAME': 'cltbld', 17:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:27 INFO - 'MOZ_NO_REMOTE': '1', 17:14:27 INFO - 'NO_EM_RESTART': '1', 17:14:27 INFO - 'PAGER': '/bin/cat', 17:14:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:27 INFO - 'PWD': '/builds/slave/test', 17:14:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:27 INFO - 'SHELL': '/bin/bash', 17:14:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:14:27 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:14:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:27 INFO - 'USER': 'cltbld', 17:14:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:27 INFO - 'XPC_FLAGS': '0x0', 17:14:27 INFO - 'XPC_SERVICE_NAME': '0', 17:14:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:27 INFO - Downloading/unpacking blobuploader==1.2.4 17:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:29 INFO - Downloading blobuploader-1.2.4.tar.gz 17:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:14:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:14: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 17:14: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 17:14: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 17:14: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 17:14: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 17:14: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 17:14:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:14:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:31 INFO - Downloading docopt-0.6.1.tar.gz 17:14:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:14:31 INFO - Installing collected packages: blobuploader, requests, docopt 17:14:31 INFO - Running setup.py install for blobuploader 17:14:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:14:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:14:31 INFO - Running setup.py install for requests 17:14:31 INFO - Running setup.py install for docopt 17:14:31 INFO - Successfully installed blobuploader requests docopt 17:14:31 INFO - Cleaning up... 17:14:31 INFO - Return code: 0 17:14:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:32 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 0x10c947a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c94d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0b46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, '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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:32 INFO - 'HOME': '/Users/cltbld', 17:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:32 INFO - 'LOGNAME': 'cltbld', 17:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:32 INFO - 'MOZ_NO_REMOTE': '1', 17:14:32 INFO - 'NO_EM_RESTART': '1', 17:14:32 INFO - 'PAGER': '/bin/cat', 17:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:32 INFO - 'PWD': '/builds/slave/test', 17:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:32 INFO - 'SHELL': '/bin/bash', 17:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:14:32 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:32 INFO - 'USER': 'cltbld', 17:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:32 INFO - 'XPC_FLAGS': '0x0', 17:14:32 INFO - 'XPC_SERVICE_NAME': '0', 17:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-XcAM5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-4fkext-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-rMbVpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-PQlwgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-3oVva7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-0Y6KWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-xzhBpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-JRa0Uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-AZrYyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-VLgmnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-yKihXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-RFlOEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-K9M4Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-aT2MOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-zd9bEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-qHTmyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:33 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Ct3dkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:14:34 INFO - Running setup.py install for manifestparser 17:14:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:14:34 INFO - Running setup.py install for mozcrash 17:14:34 INFO - Running setup.py install for mozdebug 17:14:34 INFO - Running setup.py install for mozdevice 17:14:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:14:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:14:34 INFO - Running setup.py install for mozfile 17:14:34 INFO - Running setup.py install for mozhttpd 17:14:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:14:34 INFO - Running setup.py install for mozinfo 17:14:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Running setup.py install for mozInstall 17:14:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Running setup.py install for mozleak 17:14:35 INFO - Running setup.py install for mozlog 17:14:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Running setup.py install for moznetwork 17:14:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Running setup.py install for mozprocess 17:14:35 INFO - Running setup.py install for mozprofile 17:14:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:14:35 INFO - Running setup.py install for mozrunner 17:14:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:14:36 INFO - Running setup.py install for mozscreenshot 17:14:36 INFO - Running setup.py install for moztest 17:14:36 INFO - Running setup.py install for mozversion 17:14:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:14:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:14:36 INFO - Cleaning up... 17:14:36 INFO - Return code: 0 17:14:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:36 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 0x10c947a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c94d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0b46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, '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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:36 INFO - 'HOME': '/Users/cltbld', 17:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:36 INFO - 'LOGNAME': 'cltbld', 17:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:36 INFO - 'MOZ_NO_REMOTE': '1', 17:14:36 INFO - 'NO_EM_RESTART': '1', 17:14:36 INFO - 'PAGER': '/bin/cat', 17:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:36 INFO - 'PWD': '/builds/slave/test', 17:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:36 INFO - 'SHELL': '/bin/bash', 17:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:14:36 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:36 INFO - 'USER': 'cltbld', 17:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:36 INFO - 'XPC_FLAGS': '0x0', 17:14:36 INFO - 'XPC_SERVICE_NAME': '0', 17:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-H1iV3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:36 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-6jcf6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-vmmKF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-R9Dfz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-QTutuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-AmwZEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-kbMSRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Ywi9rO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-8U_nKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-varkAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:37 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-u4NaIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:38 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-gnHEY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:38 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-5HQGns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:38 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-T6RSOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:38 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-lWGkph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:38 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-D_w2rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:38 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-8zDq4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:14:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:41 INFO - Downloading blessings-1.6.tar.gz 17:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:14:41 INFO - Installing collected packages: blessings 17:14:41 INFO - Running setup.py install for blessings 17:14:41 INFO - Successfully installed blessings 17:14:41 INFO - Cleaning up... 17:14:41 INFO - Return code: 0 17:14:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:14:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:41 INFO - Reading from file tmpfile_stdout 17:14:41 INFO - Current package versions: 17:14:41 INFO - blessings == 1.6 17:14:41 INFO - blobuploader == 1.2.4 17:14:41 INFO - docopt == 0.6.1 17:14:41 INFO - manifestparser == 1.1 17:14:41 INFO - mozInstall == 1.12 17:14:41 INFO - mozcrash == 0.17 17:14:41 INFO - mozdebug == 0.1 17:14:41 INFO - mozdevice == 0.48 17:14:41 INFO - mozfile == 1.2 17:14:41 INFO - mozhttpd == 0.7 17:14:41 INFO - mozinfo == 0.9 17:14:41 INFO - mozleak == 0.1 17:14:41 INFO - mozlog == 3.2 17:14:41 INFO - moznetwork == 0.27 17:14:41 INFO - mozprocess == 0.22 17:14:41 INFO - mozprofile == 0.28 17:14:41 INFO - mozrunner == 6.11 17:14:41 INFO - mozscreenshot == 0.1 17:14:41 INFO - mozsystemmonitor == 0.3 17:14:41 INFO - moztest == 0.7 17:14:41 INFO - mozversion == 1.4 17:14:41 INFO - psutil == 3.1.1 17:14:41 INFO - requests == 1.2.3 17:14:41 INFO - wsgiref == 0.1.2 17:14:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:41 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 0x10c947a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c94d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0b46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, '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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:41 INFO - 'HOME': '/Users/cltbld', 17:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:41 INFO - 'LOGNAME': 'cltbld', 17:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:41 INFO - 'MOZ_NO_REMOTE': '1', 17:14:41 INFO - 'NO_EM_RESTART': '1', 17:14:41 INFO - 'PAGER': '/bin/cat', 17:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:41 INFO - 'PWD': '/builds/slave/test', 17:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:41 INFO - 'SHELL': '/bin/bash', 17:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:14:41 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:41 INFO - 'USER': 'cltbld', 17:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:41 INFO - 'XPC_FLAGS': '0x0', 17:14:41 INFO - 'XPC_SERVICE_NAME': '0', 17:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:14:42 INFO - Cleaning up... 17:14:42 INFO - Return code: 0 17:14:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:42 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 0x10c947a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c94d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0b46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, '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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:14:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:14:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:14:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:42 INFO - 'HOME': '/Users/cltbld', 17:14:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:42 INFO - 'LOGNAME': 'cltbld', 17:14:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:42 INFO - 'MOZ_NO_REMOTE': '1', 17:14:42 INFO - 'NO_EM_RESTART': '1', 17:14:42 INFO - 'PAGER': '/bin/cat', 17:14:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:42 INFO - 'PWD': '/builds/slave/test', 17:14:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:42 INFO - 'SHELL': '/bin/bash', 17:14:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:14:42 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:14:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:42 INFO - 'USER': 'cltbld', 17:14:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:42 INFO - 'XPC_FLAGS': '0x0', 17:14:42 INFO - 'XPC_SERVICE_NAME': '0', 17:14:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:42 INFO - Downloading/unpacking jsonschema 17:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:14:44 INFO - Downloading/unpacking functools32 (from jsonschema) 17:14: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 17:14: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 17:14: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 17:14: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 17:14: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 17:14: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 17:14:45 INFO - Downloading functools32-3.2.3-2.tar.gz 17:14:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:14:45 INFO - warning: no files found matching '*.txt' 17:14:45 INFO - no previously-included directories found matching 'build' 17:14:45 INFO - no previously-included directories found matching 'dist' 17:14:45 INFO - no previously-included directories found matching '.git*' 17:14:45 INFO - Installing collected packages: jsonschema, functools32 17:14:45 INFO - Running setup.py install for functools32 17:14:45 INFO - warning: no files found matching '*.txt' 17:14:45 INFO - no previously-included directories found matching 'build' 17:14:45 INFO - no previously-included directories found matching 'dist' 17:14:45 INFO - no previously-included directories found matching '.git*' 17:14:45 INFO - Successfully installed jsonschema functools32 17:14:45 INFO - Cleaning up... 17:14:45 INFO - Return code: 0 17:14:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:14:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c947a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c94d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa0b46bfc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, '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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:14:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:14:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:14:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:45 INFO - 'HOME': '/Users/cltbld', 17:14:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:45 INFO - 'LOGNAME': 'cltbld', 17:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:45 INFO - 'MOZ_NO_REMOTE': '1', 17:14:45 INFO - 'NO_EM_RESTART': '1', 17:14:45 INFO - 'PAGER': '/bin/cat', 17:14:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:45 INFO - 'PWD': '/builds/slave/test', 17:14:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:45 INFO - 'SHELL': '/bin/bash', 17:14:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:14:45 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:14:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:45 INFO - 'USER': 'cltbld', 17:14:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:45 INFO - 'XPC_FLAGS': '0x0', 17:14:45 INFO - 'XPC_SERVICE_NAME': '0', 17:14:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:45 INFO - Downloading/unpacking flake8 17:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:14:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:14:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:14:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:14:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:14:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:14:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:14:49 INFO - no previously-included directories found matching 'docs/_build' 17:14:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:14:49 INFO - Running setup.py install for pep8 17:14:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:14:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:14:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:14:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:14:49 INFO - no previously-included directories found matching 'docs/_build' 17:14:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:14:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:14:49 INFO - Cleaning up... 17:14:49 INFO - Return code: 0 17:14:49 INFO - Running post-action listener: _resource_record_post_action 17:14:49 INFO - Running post-action listener: _start_resource_monitoring 17:14:49 INFO - Starting resource monitoring. 17:14:49 INFO - ##### 17:14:49 INFO - ##### Finished create-virtualenv step (success) 17:14:49 INFO - ##### 17:14:49 INFO - ##### 17:14:49 INFO - ##### Running install step. 17:14:49 INFO - ##### 17:14:49 INFO - Running pre-action listener: _resource_record_pre_action 17:14:49 INFO - Running main action method: install 17:14:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:50 INFO - Reading from file tmpfile_stdout 17:14:50 INFO - Detecting whether we're running mozinstall >=1.0... 17:14:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:14:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:14:50 INFO - Reading from file tmpfile_stdout 17:14:50 INFO - Output received: 17:14:50 INFO - Usage: mozinstall [options] installer 17:14:50 INFO - Options: 17:14:50 INFO - -h, --help show this help message and exit 17:14:50 INFO - -d DEST, --destination=DEST 17:14:50 INFO - Directory to install application into. [default: 17:14:50 INFO - "/builds/slave/test"] 17:14:50 INFO - --app=APP Application being installed. [default: firefox] 17:14:50 INFO - mkdir: /builds/slave/test/build/application 17:14:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:14:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:15:24 INFO - Reading from file tmpfile_stdout 17:15:24 INFO - Output received: 17:15:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:15:24 INFO - Running post-action listener: _resource_record_post_action 17:15:24 INFO - ##### 17:15:24 INFO - ##### Finished install step (success) 17:15:24 INFO - ##### 17:15:24 INFO - ##### 17:15:24 INFO - ##### Running run-tests step. 17:15:24 INFO - ##### 17:15:24 INFO - Running pre-action listener: _resource_record_pre_action 17:15:24 INFO - Running main action method: run_tests 17:15:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:15:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:15:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:15:24 INFO - 2016-05-31 17:15:24.673 screenresolution[1647:9761] starting screenresolution argv=screenresolution get 17:15:24 INFO - 2016-05-31 17:15:24.676 screenresolution[1647:9761] Display 0: 1600x1200x32@60 17:15:24 INFO - 2016-05-31 17:15:24.681 screenresolution[1648:9763] starting screenresolution argv=screenresolution list 17:15:24 INFO - Available Modes on Display 0 17:15:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:15:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:15:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:15:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:15:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:15:24 INFO - Intel Iris: 17:15:24 INFO - Chipset Model: Intel Iris 17:15:24 INFO - Type: GPU 17:15:24 INFO - Bus: Built-In 17:15:24 INFO - VRAM (Dynamic, Max): 1536 MB 17:15:24 INFO - Vendor: Intel (0x8086) 17:15:24 INFO - Device ID: 0x0a2e 17:15:24 INFO - Revision ID: 0x0009 17:15:24 INFO - Displays: 17:15:24 INFO - Display: 17:15:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:15:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:15:24 INFO - Display Serial Number: 1600x1200 60 17:15:24 INFO - Main Display: Yes 17:15:24 INFO - Mirror: Off 17:15:24 INFO - Online: Yes 17:15:24 INFO - Rotation: Supported 17:15:24 INFO - Return code: 0 17:15:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:15:24 INFO - Python 2.7.3 17:15:24 INFO - Return code: 0 17:15:24 INFO - grabbing minidump binary from tooltool 17:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:15:24 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 0x10c7ebd30>, '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 0x10c60fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c7e2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:15:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:15:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:15:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:15:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:15:25 INFO - Return code: 0 17:15:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:15:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:15:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:15:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:15:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:15:26 INFO - Return code: 0 17:15:26 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/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 17:15:26 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/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --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 17:15:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z6Z9Wkfdy3/Render', 17:15:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:15:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:15:26 INFO - 'HOME': '/Users/cltbld', 17:15:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:15:26 INFO - 'LOGNAME': 'cltbld', 17:15:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:15:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:26 INFO - 'MOZ_NO_REMOTE': '1', 17:15:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:15:26 INFO - 'NO_EM_RESTART': '1', 17:15:26 INFO - 'PAGER': '/bin/cat', 17:15:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:26 INFO - 'PWD': '/builds/slave/test', 17:15:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:15:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:15:26 INFO - 'SHELL': '/bin/bash', 17:15:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vppvHXcBCy/Listeners', 17:15:26 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 17:15:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:15:26 INFO - 'USER': 'cltbld', 17:15:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:15:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:26 INFO - 'XPC_FLAGS': '0x0', 17:15:26 INFO - 'XPC_SERVICE_NAME': '0', 17:15:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:15:26 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/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 17:15:26 INFO - mozversion application_buildid: 20160531141745 17:15:26 INFO - mozversion application_changeset: d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 17:15:26 INFO - mozversion application_display_name: Nightly 17:15:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:15:26 INFO - mozversion application_name: Firefox 17:15:26 INFO - mozversion application_remotingname: firefox 17:15:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:15:26 INFO - mozversion application_vendor: Mozilla 17:15:26 INFO - mozversion application_version: 49.0a1 17:15:26 INFO - mozversion platform_buildid: 20160531141745 17:15:26 INFO - mozversion platform_changeset: d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 17:15:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:15:26 INFO - mozversion platform_version: 49.0a1 17:15:26 INFO - using testdate: 1464740126 17:15:26 INFO - actual date: 1464740126 17:15:26 INFO - starting webserver on 'localhost:49278' 17:15:26 INFO - SUITE-START | Running 2 tests 17:15:26 INFO - TEST-START | dromaeo_css 17:15:26 INFO - operating with platform_type : mac_ 17:15:26 INFO - Initialising browser for dromaeo_css test... 17:15:26 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpbHLQ3g/profile http://localhost:49278/getInfo.html) 17:15:29 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 17:15:29 INFO - PROCESS | 1666 | colorDepth:24 17:15:29 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 17:15:29 INFO - PROCESS | 1666 | __metrics 17:15:29 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 17:15:30 INFO - TEST-INFO | 1666: exit 0 17:15:30 INFO - Browser initialized. 17:15:30 INFO - Running cycle 1/1 for dromaeo_css test... 17:15:30 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpbHLQ3g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:42 INFO - PROCESS | 1670 | RSS: Main: 252334080 17:15:42 INFO - PROCESS | 1670 | 17:16:31 INFO - PROCESS | 1670 | 1464740191685 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:18:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 17:18:44 INFO - PROCESS | 1670 | RSS: Main: 264732672 17:18:44 INFO - PROCESS | 1670 | 17:21:46 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 17:21:47 INFO - PROCESS | 1670 | RSS: Main: 275902464 17:21:47 INFO - PROCESS | 1670 | 17:24:49 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 17:24:50 INFO - PROCESS | 1670 | RSS: Main: 277938176 17:24:50 INFO - PROCESS | 1670 | 17:27:51 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 17:27:52 INFO - PROCESS | 1670 | RSS: Main: 279412736 17:27:52 INFO - PROCESS | 1670 | 17:30:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 17:30:55 INFO - PROCESS | 1670 | RSS: Main: 282894336 17:30:55 INFO - PROCESS | 1670 | 17:33:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 17:33:58 INFO - PROCESS | 1670 | RSS: Main: 283246592 17:33:58 INFO - PROCESS | 1670 | 17:33:58 INFO - PROCESS | 1670 | __start_tp_report 17:33:58 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 17:33:58 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 17:33:58 INFO - PROCESS | 1670 | |i|pagename|runs| 17:33:58 INFO - PROCESS | 1670 | |0;dojo.html;2886.6968968258334;3507.87722429715;3516.5428494295747;3530.7704999175053;3532.9823350883244;5781.797637082703;5817.925795911489;5860.179574859523;5893.469587737108;5911.083782013784;5708.91530609184;5794.913076303848;5821.854453638661;5867.7066146692705;5899.911501327473;5216.843494695166;5237.292965449673;5289.418164001957;5301.6818990860675;5362.892742145154;5274;5374.355077390699;5402.702851343174;5429.0227759003365;5446.700431476267;4378.255696531597;5176.430592634807;5226.346706661668;5237.502437268454;5314.468553144674;4440.578145076245;5321.73391330432;5330.493603107738;5332.546733527654;5336.43967383423;4447.577480139382;4514.735873955297;5156.510131537498;5296.814611488618;5303.8408847734245;4308.82764689412;5558.8604336111;5738.225339579145;5755.194272801811;5853.356130825606;6244.157038799749;6265.4361107500545;6281.560290779647;6295.307516173218;6300.716467758962;6208.844778880519;6221.937780622181;6252.248291298601;6277.058441233806;6302.653354065525;5937.346891841894;6013.037913933794;6013.729382177812;6019.518438524908;6092.208012958287;6352.841178970562;6390.840228994312;6409.1668083149825;6418.74325027003;6441.323661015525;6112.419320164578;6168.414000669929;6196.411340922605;6258.812235632939;6286.968565157145;6246.781362652286;6302.716377763011;6315.4947604192475;6327.335629758809;6341.524385671093;5643.604947653625;5859.062549991981;6182.876342473215;6281.59169653971;6302.432781049728;6178.845528861724;6283.214598175228;6299.6850157491945;6301.558890877686;6309.810705678837;4993.251012348177;5011.6241281904;5016.02187573427;5034.270030845507;5050.393952725696;4483.21543729846;4485.932711009337;4505.797239124247;4512.029913568533;4521.412216411845;4801.375821143299;5842.065269556851;5958.970205148947;5994.820155395345;6038.5169186465855;6240.282367527701;6241.126242326078;6269.592476489013;6270.184875966186;6284.37156284377;5962.165296858443;5964.314103878023;5972.3125338151285;6003.459688628044;6045.486133678599;5347.86630334237;5495.093309603871;5511.5039646432015;5522.0336441122645;5541.085720856014;5444.891102178013;6551.63965981866;6558.508111891627;6574.967125164344;6593.505487088391;6267.091271765659;6299.622022678654;6304.5271604628915;6304.6847657617855;6306.432421082125;6279.811605651837;6412.871742565029;6421.5825971311715;6465.965445528693;6561.40947314744;6249.687515624109;6278.591891527036;6282.4974002080635;6292.244930608356;6299.30707622152;5272.6309158358545;6264.2796078451565;6279.497640188682;6302.684865756785;6302.842428939313;6044.274687037584;6081.878362432638;6102.368068311607;6129.295131059986;6231.2522497300615;6163.106349579376;6177.351378105414;6216.564840461125;6231.1276421300145;6315.08431277453;5457.608309880955;6147.477464415575;6161.969190154021;6162.168107305456;6202.100695399232;6098.280951554018;6145.877082458236;6149.385061493815;6178.443940045418;6270.372962703693;6098.778248023657;6128.203333566607;6144.17053697763;6150.630962142286;6150.630962142286 17:33:58 INFO - PROCESS | 1670 | |1;ext.html;495.6258025190981;516.3004129404654;523.756453249239;532.2043544900374;558.5643198305323;34720.958371248875;34793.65206347942;41200;42112.736617901435;42146;41314.58685413145;42952.14095718084;43676.56323436765;43689.126217475634;43839.56160438395;9416.905830941689;9469.100435458655;9596.184324332406;9682.75793105171;9690.418574885529;9572.904270957306;9653;9653.02113066823;9693.515324233795;9703.563339649732;9619.134277914985;9689.51552422376;9699.806003879918;9706.174975127122;9716.368436051669;9592.472414017226;9598.328117031811;9672.75818104546;9692.660756873476;9700.805983880318;9405.247580193569;9455.90544094564;9456.101670341306;9528.333016688835;9548.092931171528;40344.39483407751;40418.59581404178;40853.59146408528;40881.79559102185;40936.79531602353;3439.8280085995852;3493.8427770750377;3584.791611507712;3590.120420496995;3735.1035751419736;32201.677983220103;32481.67518324834;32612.510812337834;33195.50206746901;33344.33311333784;3253.8210398428077;3293.4236508610898;3323.8670453181844;3404.0468668772783;3421.8973430797114;504351;504745;510797;511131;518802;35099.298014039574;64035.67982160152;64158;64406.67796660987;64421;35877.641223587954;35981.46027809585;36798.44802327967;36890.077748056516;36980;15091.396344146111;15226.467073652591;15387.461438849587;15429.537113886601;15474.845251547566;12910.483580656857;12912.548060817826;13034.543790967271;13066.934665326802;13067.215967042008;6700.497462690318;7779.72770953014;7947.841043179104;8001.839963200703;8009.759707208793;3255.088575198948;3256.47896336585;3293.9176520587175;3318.622771549828;3374.905367175344;843.3759018326517;912.7626817027488;1041.265907535186;1051.3218973762034;1055.6147006342783;29859.552106718416;30665.540016899766;30745.077647670158;31165.844170779;31209.687903120677;1011.1657882247175;1019.1184625299063;1087.5377964365114;1102.4763237462303;1130.5133749119882;15827.129507877178;16172.110533920511;16247;16883.32466701318;17149.971001739934;671856;680027;683093;688759;688784;61730;62158.892055397046;62252.06621900675;62329;62365;1352.8579499152645;1355.9932200338935;1398.8461269260374;1401.6075498860334;1402.3198748606917;27892.860535697193;31828.5225721619;31991.520127197644;32587.18532036718;32685.673143268265;59688.403115970024;60129;60503;60573.69713151406;60593;533758.6624133709;534593;593863;604094;608372;45241.77379113215;45413.54586454093;45534.861628459556;46145.30782038205;46333;48806.75596621994;49024.26463602977;49769.00923963001;49994.00011999813;51377.7431112842;40279.59720402758;40703;41999.58000419957;42178.789106054275;43468.782656086514;41742.79128604459;42396.78801605972;43605.56394436142;43692;43907;36489.270214596094;42434.787826060674;42762;43059.78470107629;43178.78410608052;43276.78361608172;43379.566204338815;43505.129897402505;43623;43916.78041609772;23186.536269274857;26624.600630990164;27704.86147569249;27769.722302776714;27795.861020694767 17:33:58 INFO - PROCESS | 1670 | |2;jquery.html;347.1390950442902;380.8343370633773;387.9592642772509;398.4600865826803;406.29507803960126;18488.630227395442;19191.32830350938;19752.604947901033;20360.69458958113;21322.89338553307;19775.901120494414;19917.40247792565;20276.59446811063;20467.48831279219;20821;5645.15322701594;5724.427557244274;5962.284525856903;5962.522998160159;6008.218931538916;5815.273090863642;5861.941380586203;5873.823785286449;5917.970410147943;5952.345242023374;5752.2234498342605;5793.131030345461;5795.2466179396615;5877.235959325303;5930.080837470177;5751.712414379264;5918.704064796786;5920.881582368372;5922.88154236915;5932.822015339547;5075.467075957008;5115.514026167508;5135.6148288878485;5142.794288228466;5153.304303918961;25203;25344;25415.61876571853;25585.23244302674;25779.742202577923;2232.8325375596896;2258.153192552793;2318.8260880434036;2617.41108250644;2654.933626659349;13503.59489215325;14317.926155538273;14853.331600078023;15388.30752616135;15832.20838958059;1564.6244901223738;1587.9920600396924;1596.696627640756;1638.6394993101499;1711.0674682298177;90410.54794726051;109464.4526777369;109988.45005775;111441.44279278551;112051;10387.766630174548;10614.991575800286;10692.305000174963;10976.12191024716;11062.612808551663;13393;13397.196168229937;13482;13504.122232054886;13517.32413379327;8399.328053755686;8575.99299268916;8587.184217499327;8645.394822362374;8679.826403471896;6350.745970161234;8207.891934588753;8256.091829898707;8458.773477845802;8787.077356877437;5681.460261275172;6594.07682924391;6596.175478065242;6596.80209593713;6682.799516014527;1845.344902559599;1853.2031226572433;1870.5136664467336;1871.737956686065;1884.4723477426342;902.2962089570145;972.7957129002847;1006.5118417567473;1034.2656713733334;1066.274933045521;19561;20763.065662045243;20821;21878.45303867416;21888.343349699535;878.4246318661287;908.4413085952092;1032.4321623107262;1035.2804800663616;1072.8980746829038;10586.153107751361;11004.404361367557;11023.779524409627;11029.117670586334;11062.05972492331;575752;578612;611645;615753;621309;112399.82602348742;137409.3129534366;143899;145353.27323363317;147082.26458867636;1032.2155162076876;1057.1912486947492;1067.2155965365296;1075.9731006724894;1077.3105212663902;17221.741673875153;18246.72629910579;18326.99201543928;18482.81517184811;18485.630287394444;53083.20375194452;54498.727506362215;55160.44839551553;56145.43854561402;56204.15693764515;62697.17862696099;74968.25031749831;79143.81284280625;83024.58487707523;84992.72510912217;5198.844034678966;6291.496680265476;8057.597120143852;8123.593820309079;8394.664213431395;10455.68632941013;13122.737545249232;13202.405891735087;13302.334883255991;13408.262545560088;11187.944060279646;11228.55085796559;11462.197646165023;11506.252093613888;11564.421778910853;10460.529276182742;11195.664130076111;11251.718707032389;11291.710821554381;11336.773264534499;8038.718644847402;11100.833487497855;11468.541258349906;11642.592509262135;11693.123015774021;6834.692438840363;7512.399008079231;7544.773656790305;7579.431542634215;7773.378129749493;10121.342112762646;10406.323588966692;11453.599124030952;11603.477843496894;11619 17:33:58 INFO - PROCESS | 1670 | |3;mootools.html;1448.7464693678608;1554.7978762760843;1780.8397244248024;1894.08137053529;1921.5196200949763;8158.265756081966;8711.564421778901;8973.282137429007;9329.160375566198;9357.344985850985;8847.247983921374;8925.910740892588;9065.954670226656;9077.773055673611;9080.636774529028;2352.541254455386;2373.9881300593474;2379.5954687703047;2383.1539803369815;2386.3914701751028;2365.1840115160244;2376.1089591403224;2383.559041577305;2413.348395933097;2423.6485709572103;2343.179887039531;2364.798992085675;2367.9052837886466;2417.9637305440433;2432.62294344376;2360.5514952159033;2374.2402431222017;2377.084822343393;2387.7015373078366;2416.9879150604133;2375.4180225844607;2377.762223777626;2400.1599440195964;2409.5903696371483;2414.1429792423614;8989.955050224773;9033.548322583845;9039.68361107358;9045.321600879895;9049.457032578066;5162.354705661793;5403.567714582863;5454.8909021819345;5477.123660214346;5566.721663916788;2932.618759561243;3609.097725568608;3724.627537246281;3744.1762812181278;3784;1087.597588892107;1103.0623969625833;1111.149970272742;1118.4128332625355;1125.5610311978253;11913.88086119145;12145.574904878287;12147.635570932885;12158.088143389281;12159.270443773403;4775.498572649891;4777.092352453093;4909.754512274443;4922.4339200992345;4932.112219800399;9935.453550054817;10190.745231369126;10207.846882296772;10231.693049208536;10233.846492302622;5349.1976203569775;5362.249285100089;5443.237946687467;5454.400015998237;5477.616566840283;4498.347739577808;4868.902621947541;4982.302477653131;5024.346834911438;5029.119904016856;3986.5016872890887;4063.918721625551;4078.8164532596106;4105.4252404663375;4122.381642753611;1108.7948729485072;1344.3547097393307;1349.8380194376737;1362.6661467940419;1366.6538459617268;759.8746206875803;945.3288165402504;956.6747305916022;969.5249327829313;975.1857199238574;6076.453119219292;6130.264368275835;6187.917114504944;6200.472959798378;6226.782062627786;412.75965723002787;424.12629982236075;437.5362116156884;445.2097526889797;453.975031373271;4080.8775736727944;4097.364908439196;4105.507339119325;4128.298189307825;4161.583841615875;10389.584416623251;10694.62568810088;10823.945880270549;10824.296420732628;10829;10680.038796508352;10695.411752353539;10760.461976901124;10765.515551800188;10784.94607526973;7082.654295683804;8239.546824924686;8258.42191046633;8304.8339033219;8342.66629334832;6219.968900155471;6227.719752611053;6300.369963003663;6306.590071645328;6350.872982540416;9890.851637225303;10069.244806639677;10308.175345972455;10319.587216511256;10346.586136554453;10263.538140783534;10688.786224275627;10706.625268115578;10724.356538607708;10744.086752626114;7150.284971502808;7592;7710.845783084419;7788.571628560484;7873.291403773688;8484.066752657327;8624.137586241326;8668.176523230282;8731.650733970824;8783.868241976248;7528.209537998431;7637.274458926393;7696.7306144284685;7709.113451953097;7776.572288524186;7491.70033198666;7557.622118894144;7573.35626471756;7678.347340475899;7739.92260077392;7181.461390395637;7267.127944646676;7278.344948954619;7280.126384833854;7290.671919763729;5008.073506401327;5132.666376685504;5144.022635699205;5223.190405487156;5229;5224;5249.947500525099;5257.5005374489365;5290.550303224269;5302.867428314323 17:33:58 INFO - PROCESS | 1670 | |4;prototype.html;306.975441964643;315.057976649817;320.39445448103083;325.451614030359;326.52164578891916;4269.487661480619;5065.4934506549325;5264.526192642661;5558.6942718150485;5690.715464226788;4711.623070154388;4747.05058988202;5429.782808687658;5527.834164975057;5559.444055594438;3322.401967645823;3912.139329347553;4226.281532139536;4238.618524332809;4247.936280955788;3685.834137463807;3783.959411161925;4239.9576004240025;4257.169851878885;4274.487061552602;4136.255474014676;4177.853775117872;4180.581941805825;4180.745776267123;4224.767637779936;3531.7704349217347;3689.6863766579604;4210.115875666083;4227.252286014084;4230.259704551707;3318.6891177984803;3399.507071474647;3674.4488326751202;3683.631636836322;3699.5560532735976;4915.852524424273;5002.574781143607;5131.229725744613;5257.316548848626;5387.81142660005;4487.8429254976245;4854.757262136879;4864.7324397158145;4896.975515122437;4936.80252789888;5155.097857874857;5884.617499862496;5886.470217680387;5915.763369465216;5930.436608522184;3786.9621303786885;4088.325426304656;4090.8159132839096;4100.261952848486;4131.5248746393945;5911.379305172938;6178.536609754242;6178.598391104564;6185.577312221738;6231.221097362784;3459.7405194610506;3837.213371258885;4046.9595304046584;4105.568915263914;4138.8551400700835;4051.817668204938;4149.522804877417;4168.6665066794585;4175.394567787715;4185.121124563815;3635.527381440396;4547.272436410225;4657.976710116428;4693.9765301173275;4787;4446.866594002185;5420.4850539198715;5592.916106258409;5862.5896187267335;5882.382349853289;1374.1311815930326;1399.6710772968345;1427.1365823676733;1450.2893582144673;1453.9604183009199;818.6520728690402;828.9182616685163;874.8993865705526;906.945583264993;912.9224015958586;4113.9509425097;4184.079502509443;4218.957810421856;4470.418845550058;4640;4617.58441740245;4643.883902902387;4740.19416699162;4946.579540739005;5139.460356662496;4229.555896630871;4232.2647714436935;4307.353896915425;4560.426652804777;4586.587207151306;2500.6124050771796;2569.717331093578;2731.003183837885;2737.2891942535916;2743.5596311936392;4638.304254361873;4859.343988561499;4877.926831097537;5180.156553622375;5396.219247648267;4373.479349123986;4405.185040767404;4509.519764129715;4625.8843528911375;5167.10402535027;4116.9090191099385;4700.918788678555;4792.400949881265;4924.704517728947;5391.086164411656;4237.745735255895;4406.845760398371;4728.739919304471;5062.468765617192;5066.6440105351185;4689.507601701908;5047.343845300087;5096.108181068224;5108.284840122312;5173.292813371503;4989.476105009067;5764.7405866736935;6419;6419.903701444576;6432.292447830829;3441.2773317603583;3657.7622454541433;3934.193490334532;4006.2973236375005;4015.799210039545;5524.66851988882;5605.947213490864;5852.385499522659;5859.765609375577;6128.816135515942;4919;4925.753712314442;5205.609579281493;5868.413158684269;5881.294604481815;5386.757595908115;5922.407759224043;5928.436798504135;5944.3164036136395;5954.583179177416;5026.52248036436;5117.053345131163;5261.89476210466;5680.375158732453;5758.769649213985;5301.970502418267;5681.630694004876;5911.290645122613;5932.584719069796;5934.881302374015;5377.596680248918;5560.972195138998;5569.33168019824;5610.43895610452;5836.505890464404 17:33:58 INFO - PROCESS | 1670 | |5;yui.html;709.4147328454026;720.9639518024101;777.1373775109631;777.6422845491073;856.4304737349663;14734.410623575057;15260.847391526082;15416.460423885166;15426.922865385686;16090.678186436293;15975.84024159758;15990.360385584563;15995.040297582123;16010.119443430607;16019.759703604455;694.5728377048105;885.4952676974111;886.2599729226104;896.4352457951514;897.9102089791002;873.3973558244822;887.8313120507115;888.9288856891433;898.6989358564889;899.5996781432265;809;938.1181689212153;955.4362925873733;955.7132860141965;956.669948867643;925.6806401791405;945.1588086602928;946.4463288975958;948.7011591348737;953.2421724728857;710.662435343213;711.3491155592626;711.5055036749461;713.1264201353353;713.2510863593258;14618.70762584753;14690.338934747962;14811.555653330417;14814.259287035606;14842.406303747837;13385.531506397327;13478.989075819352;13480.595582132453;13519.391627376694;13553.39009744564;11434.885651143466;11718.622004438359;11836.420901222662;11909.761804763943;12154.756904861943;3227.677232276777;3290.24794047834;3771.962280377216;3773.1699026214187;3773.52830896138;80255.59872200602;80257;81699;81738;81822;10522.473876306154;10523.210759192938;10630.149588032938;10677.786444271227;11015.889841101487;20481.2831550895;20752.48118797012;20940.267090651752;20944.685829712565;21005.15979360839;7706.267904549059;7754.9224507755325;7850.332721718603;7911.762647120596;8014.438989270695;4453;4460.487043989918;4470.307102399133;4494.797734101973;4594.196015697306;3494.965050349464;3580.409232476612;3598.7300952428673;3603.1532589841368;3608.314420260142;582.9009068458372;614.5114633866087;677.3801971196399;683.3712984054681;699.1191099214924;609.411917499615;687.8796210663114;696.4115322552435;717.5013365710885;717.7452004538313;7792.2986931176465;8091.878621820678;9342.392744471588;9412.529373531295;9433.858492122623;492.7683988525459;637.611057255074;648.8410339466861;661.8411581220569;674.5008693566725;4348.499922508889;4575;4855.32025516428;5002.74986250686;5011.173156429221;93940.53029734807;96016.51991739997;97762;98465.01534984559;98516.50741746389;84951.57524212463;85038.14961850426;85953;86038.13961860427;86795.56602216949;76401;78188.60905695434;78753.60623196962;78936;79164.60417697874;9415.717528474157;9539.427634341962;9705.9029409705;9755.658551950932;9763.414195148314;48365.7908552275;48478.5152148474;48889.266661000824;48936.75531622319;49534.256986144515;52778;67328;67870.3212967864;68102.31897681158;68355.65822170857;12920.806187907001;12967.01877759398;13020.41408136655;13039.674008149872;13333.266670333225;14372;14373.784393234318;14554.345054472362;14590.781138283146;15106.320215590544;12372.434410802267;12654.936725316315;13469.46122155142;13746.96897732694;13900.668368208413;11071.667849964513;11636.592719254786;11878.346690932081;12222.388880555758;12470;12986.110076526164;13142.868571314548;13169;13286.933565332112;13318.800217996544;9696.430445931692;10722.356658600509;11423.485943132746;11455.484503197209;11475.598354057567;11191.832122518332;11254.831177532507;11498.25261358009;11506.07951363872;11506.367149806842 17:33:58 INFO - PROCESS | 1670 | __end_tp_report 17:33:58 INFO - PROCESS | 1670 | __start_cc_report 17:33:58 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,412 17:33:58 INFO - PROCESS | 1670 | __end_cc_report 17:33:58 INFO - PROCESS | 1670 | __startTimestamp1464741238646__endTimestamp 17:33:58 INFO - PROCESS | 1670 | 17:33:58 INFO - PROCESS | 1670 | ------- Summary: start ------- 17:33:58 INFO - PROCESS | 1670 | Number of tests: 6 17:33:58 INFO - PROCESS | 1670 | 17:33:58 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5794.33 Median:6098.28 stddev:690.62 (11.3%) stddev-sans-first:653.98 17:33:58 INFO - PROCESS | 1670 | Values: 2886.7 3507.9 3516.5 3530.8 3533.0 5781.8 5817.9 5860.2 5893.5 5911.1 5708.9 5794.9 5821.9 5867.7 5899.9 5216.8 5237.3 5289.4 5301.7 5362.9 5274.0 5374.4 5402.7 5429.0 5446.7 4378.3 5176.4 5226.3 5237.5 5314.5 4440.6 5321.7 5330.5 5332.5 5336.4 4447.6 4514.7 5156.5 5296.8 5303.8 4308.8 5558.9 5738.2 5755.2 5853.4 6244.2 6265.4 6281.6 6295.3 6300.7 6208.8 6221.9 6252.2 6277.1 6302.7 5937.3 6013.0 6013.7 6019.5 6092.2 6352.8 6390.8 6409.2 6418.7 6441.3 6112.4 6168.4 6196.4 6258.8 6287.0 6246.8 6302.7 6315.5 6327.3 6341.5 5643.6 5859.1 6182.9 6281.6 6302.4 6178.8 6283.2 6299.7 6301.6 6309.8 4993.3 5011.6 5016.0 5034.3 5050.4 4483.2 4485.9 4505.8 4512.0 4521.4 4801.4 5842.1 5959.0 5994.8 6038.5 6240.3 6241.1 6269.6 6270.2 6284.4 5962.2 5964.3 5972.3 6003.5 6045.5 5347.9 5495.1 5511.5 5522.0 5541.1 5444.9 6551.6 6558.5 6575.0 6593.5 6267.1 6299.6 6304.5 6304.7 6306.4 6279.8 6412.9 6421.6 6466.0 6561.4 6249.7 6278.6 6282.5 6292.2 6299.3 5272.6 6264.3 6279.5 6302.7 6302.8 6044.3 6081.9 6102.4 6129.3 6231.3 6163.1 6177.4 6216.6 6231.1 6315.1 5457.6 6147.5 6162.0 6162.2 6202.1 6098.3 6145.9 6149.4 6178.4 6270.4 6098.8 6128.2 6144.2 6150.6 6150.6 17:33:58 INFO - PROCESS | 1670 | 17:33:58 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:72477.96 Median:43119.28 stddev:158792.55 (368.3%) stddev-sans-first:159146.03 17:33:58 INFO - PROCESS | 1670 | Values: 495.6 516.3 523.8 532.2 558.6 34721.0 34793.7 41200.0 42112.7 42146.0 41314.6 42952.1 43676.6 43689.1 43839.6 9416.9 9469.1 9596.2 9682.8 9690.4 9572.9 9653.0 9653.0 9693.5 9703.6 9619.1 9689.5 9699.8 9706.2 9716.4 9592.5 9598.3 9672.8 9692.7 9700.8 9405.2 9455.9 9456.1 9528.3 9548.1 40344.4 40418.6 40853.6 40881.8 40936.8 3439.8 3493.8 3584.8 3590.1 3735.1 32201.7 32481.7 32612.5 33195.5 33344.3 3253.8 3293.4 3323.9 3404.0 3421.9 504351.0 504745.0 510797.0 511131.0 518802.0 35099.3 64035.7 64158.0 64406.7 64421.0 35877.6 35981.5 36798.4 36890.1 36980.0 15091.4 15226.5 15387.5 15429.5 15474.8 12910.5 12912.5 13034.5 13066.9 13067.2 6700.5 7779.7 7947.8 8001.8 8009.8 3255.1 3256.5 3293.9 3318.6 3374.9 843.4 912.8 1041.3 1051.3 1055.6 29859.6 30665.5 30745.1 31165.8 31209.7 1011.2 1019.1 1087.5 1102.5 1130.5 15827.1 16172.1 16247.0 16883.3 17150.0 671856.0 680027.0 683093.0 688759.0 688784.0 61730.0 62158.9 62252.1 62329.0 62365.0 1352.9 1356.0 1398.8 1401.6 1402.3 27892.9 31828.5 31991.5 32587.2 32685.7 59688.4 60129.0 60503.0 60573.7 60593.0 533758.7 534593.0 593863.0 604094.0 608372.0 45241.8 45413.5 45534.9 46145.3 46333.0 48806.8 49024.3 49769.0 49994.0 51377.7 40279.6 40703.0 41999.6 42178.8 43468.8 41742.8 42396.8 43605.6 43692.0 43907.0 36489.3 42434.8 42762.0 43059.8 43178.8 43276.8 43379.6 43505.1 43623.0 43916.8 23186.5 26624.6 27704.9 27769.7 27795.9 17:33:58 INFO - PROCESS | 1670 | 17:33:58 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:35103.44 Median:21071.95 stddev:100305.87 (476.0%) stddev-sans-first:100553.30 17:33:58 INFO - PROCESS | 1670 | Values: 347.1 380.8 388.0 398.5 406.3 18488.6 19191.3 19752.6 20360.7 21322.9 19775.9 19917.4 20276.6 20467.5 20821.0 5645.2 5724.4 5962.3 5962.5 6008.2 5815.3 5861.9 5873.8 5918.0 5952.3 5752.2 5793.1 5795.2 5877.2 5930.1 5751.7 5918.7 5920.9 5922.9 5932.8 5075.5 5115.5 5135.6 5142.8 5153.3 25203.0 25344.0 25415.6 25585.2 25779.7 2232.8 2258.2 2318.8 2617.4 2654.9 13503.6 14317.9 14853.3 15388.3 15832.2 1564.6 1588.0 1596.7 1638.6 1711.1 90410.5 109464.5 109988.5 111441.4 112051.0 10387.8 10615.0 10692.3 10976.1 11062.6 13393.0 13397.2 13482.0 13504.1 13517.3 8399.3 8576.0 8587.2 8645.4 8679.8 6350.7 8207.9 8256.1 8458.8 8787.1 5681.5 6594.1 6596.2 6596.8 6682.8 1845.3 1853.2 1870.5 1871.7 1884.5 902.3 972.8 1006.5 1034.3 1066.3 19561.0 20763.1 20821.0 21878.5 21888.3 878.4 908.4 1032.4 1035.3 1072.9 10586.2 11004.4 11023.8 11029.1 11062.1 575752.0 578612.0 611645.0 615753.0 621309.0 112399.8 137409.3 143899.0 145353.3 147082.3 1032.2 1057.2 1067.2 1076.0 1077.3 17221.7 18246.7 18327.0 18482.8 18485.6 53083.2 54498.7 55160.4 56145.4 56204.2 62697.2 74968.3 79143.8 83024.6 84992.7 5198.8 6291.5 8057.6 8123.6 8394.7 10455.7 13122.7 13202.4 13302.3 13408.3 11187.9 11228.6 11462.2 11506.3 11564.4 10460.5 11195.7 11251.7 11291.7 11336.8 8038.7 11100.8 11468.5 11642.6 11693.1 6834.7 7512.4 7544.8 7579.4 7773.4 10121.3 10406.3 11453.6 11603.5 11619.0 17:33:58 INFO - PROCESS | 1670 | 17:33:58 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5859.12 Median:4823.00 stddev:3300.41 (68.4%) stddev-sans-first:3293.03 17:33:58 INFO - PROCESS | 1670 | Values: 1448.7 1554.8 1780.8 1894.1 1921.5 8158.3 8711.6 8973.3 9329.2 9357.3 8847.2 8925.9 9066.0 9077.8 9080.6 2352.5 2374.0 2379.6 2383.2 2386.4 2365.2 2376.1 2383.6 2413.3 2423.6 2343.2 2364.8 2367.9 2418.0 2432.6 2360.6 2374.2 2377.1 2387.7 2417.0 2375.4 2377.8 2400.2 2409.6 2414.1 8990.0 9033.5 9039.7 9045.3 9049.5 5162.4 5403.6 5454.9 5477.1 5566.7 2932.6 3609.1 3724.6 3744.2 3784.0 1087.6 1103.1 1111.1 1118.4 1125.6 11913.9 12145.6 12147.6 12158.1 12159.3 4775.5 4777.1 4909.8 4922.4 4932.1 9935.5 10190.7 10207.8 10231.7 10233.8 5349.2 5362.2 5443.2 5454.4 5477.6 4498.3 4868.9 4982.3 5024.3 5029.1 3986.5 4063.9 4078.8 4105.4 4122.4 1108.8 1344.4 1349.8 1362.7 1366.7 759.9 945.3 956.7 969.5 975.2 6076.5 6130.3 6187.9 6200.5 6226.8 412.8 424.1 437.5 445.2 454.0 4080.9 4097.4 4105.5 4128.3 4161.6 10389.6 10694.6 10823.9 10824.3 10829.0 10680.0 10695.4 10760.5 10765.5 10784.9 7082.7 8239.5 8258.4 8304.8 8342.7 6220.0 6227.7 6300.4 6306.6 6350.9 9890.9 10069.2 10308.2 10319.6 10346.6 10263.5 10688.8 10706.6 10724.4 10744.1 7150.3 7592.0 7710.8 7788.6 7873.3 8484.1 8624.1 8668.2 8731.7 8783.9 7528.2 7637.3 7696.7 7709.1 7776.6 7491.7 7557.6 7573.4 7678.3 7739.9 7181.5 7267.1 7278.3 7280.1 7290.7 5008.1 5132.7 5144.0 5223.2 5229.0 5224.0 5249.9 5257.5 5290.6 5302.9 17:33:58 INFO - PROCESS | 1670 | 17:33:58 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4454.99 Median:4734.47 stddev:1362.88 (28.8%) stddev-sans-first:1330.67 17:33:58 INFO - PROCESS | 1670 | Values: 307.0 315.1 320.4 325.5 326.5 4269.5 5065.5 5264.5 5558.7 5690.7 4711.6 4747.1 5429.8 5527.8 5559.4 3322.4 3912.1 4226.3 4238.6 4247.9 3685.8 3784.0 4240.0 4257.2 4274.5 4136.3 4177.9 4180.6 4180.7 4224.8 3531.8 3689.7 4210.1 4227.3 4230.3 3318.7 3399.5 3674.4 3683.6 3699.6 4915.9 5002.6 5131.2 5257.3 5387.8 4487.8 4854.8 4864.7 4897.0 4936.8 5155.1 5884.6 5886.5 5915.8 5930.4 3787.0 4088.3 4090.8 4100.3 4131.5 5911.4 6178.5 6178.6 6185.6 6231.2 3459.7 3837.2 4047.0 4105.6 4138.9 4051.8 4149.5 4168.7 4175.4 4185.1 3635.5 4547.3 4658.0 4694.0 4787.0 4446.9 5420.5 5592.9 5862.6 5882.4 1374.1 1399.7 1427.1 1450.3 1454.0 818.7 828.9 874.9 906.9 912.9 4114.0 4184.1 4219.0 4470.4 4640.0 4617.6 4643.9 4740.2 4946.6 5139.5 4229.6 4232.3 4307.4 4560.4 4586.6 2500.6 2569.7 2731.0 2737.3 2743.6 4638.3 4859.3 4877.9 5180.2 5396.2 4373.5 4405.2 4509.5 4625.9 5167.1 4116.9 4700.9 4792.4 4924.7 5391.1 4237.7 4406.8 4728.7 5062.5 5066.6 4689.5 5047.3 5096.1 5108.3 5173.3 4989.5 5764.7 6419.0 6419.9 6432.3 3441.3 3657.8 3934.2 4006.3 4015.8 5524.7 5605.9 5852.4 5859.8 6128.8 4919.0 4925.8 5205.6 5868.4 5881.3 5386.8 5922.4 5928.4 5944.3 5954.6 5026.5 5117.1 5261.9 5680.4 5758.8 5302.0 5681.6 5911.3 5932.6 5934.9 5377.6 5561.0 5569.3 5610.4 5836.5 17:33:58 INFO - PROCESS | 1670 | 17:33:58 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:19355.87 Median:24714.76 stddev:26755.27 (108.3%) stddev-sans-first:26793.69 17:33:58 INFO - PROCESS | 1670 | Values: 709.4 721.0 777.1 777.6 856.4 14734.4 15260.8 15416.5 15426.9 16090.7 15975.8 15990.4 15995.0 16010.1 16019.8 694.6 885.5 886.3 896.4 897.9 873.4 887.8 888.9 898.7 899.6 809.0 938.1 955.4 955.7 956.7 925.7 945.2 946.4 948.7 953.2 710.7 711.3 711.5 713.1 713.3 14618.7 14690.3 14811.6 14814.3 14842.4 13385.5 13479.0 13480.6 13519.4 13553.4 11434.9 11718.6 11836.4 11909.8 12154.8 3227.7 3290.2 3772.0 3773.2 3773.5 80255.6 80257.0 81699.0 81738.0 81822.0 10522.5 10523.2 10630.1 10677.8 11015.9 20481.3 20752.5 20940.3 20944.7 21005.2 7706.3 7754.9 7850.3 7911.8 8014.4 4453.0 4460.5 4470.3 4494.8 4594.2 3495.0 3580.4 3598.7 3603.2 3608.3 582.9 614.5 677.4 683.4 699.1 609.4 687.9 696.4 717.5 717.7 7792.3 8091.9 9342.4 9412.5 9433.9 492.8 637.6 648.8 661.8 674.5 4348.5 4575.0 4855.3 5002.7 5011.2 93940.5 96016.5 97762.0 98465.0 98516.5 84951.6 85038.1 85953.0 86038.1 86795.6 76401.0 78188.6 78753.6 78936.0 79164.6 9415.7 9539.4 9705.9 9755.7 9763.4 48365.8 48478.5 48889.3 48936.8 49534.3 52778.0 67328.0 67870.3 68102.3 68355.7 12920.8 12967.0 13020.4 13039.7 13333.3 14372.0 14373.8 14554.3 14590.8 15106.3 12372.4 12654.9 13469.5 13747.0 13900.7 11071.7 11636.6 11878.3 12222.4 12470.0 12986.1 13142.9 13169.0 13286.9 13318.8 9696.4 10722.4 11423.5 11455.5 11475.6 11191.8 11254.8 11498.3 11506.1 11506.4 17:33:58 INFO - PROCESS | 1670 | -------- Summary: end -------- 17:33:58 INFO - PROCESS | 1670 | 17:33:59 INFO - TEST-INFO | 1670: exit 0 17:33:59 INFO - TEST-OK | dromaeo_css | took 1112860ms 17:33:59 INFO - TEST-START | kraken 17:33:59 INFO - operating with platform_type : mac_ 17:33:59 INFO - Initialising browser for kraken test... 17:33:59 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpQl0gn_/profile http://localhost:49278/getInfo.html) 17:34:01 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 17:34:01 INFO - PROCESS | 1678 | colorDepth:24 17:34:01 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 17:34:01 INFO - PROCESS | 1678 | __metrics 17:34:02 ERROR - PROCESS | 1678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 17:34:02 INFO - TEST-INFO | 1678: exit 0 17:34:02 INFO - Browser initialized. 17:34:02 INFO - Running cycle 1/1 for kraken test... 17:34:02 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpQl0gn_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:34:14 INFO - PROCESS | 1680 | RSS: Main: 252608512 17:34:14 INFO - PROCESS | 1680 | 17:34:17 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 17:34:18 INFO - PROCESS | 1680 | RSS: Main: 265183232 17:34:18 INFO - PROCESS | 1680 | 17:34:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 17:34:21 INFO - PROCESS | 1680 | RSS: Main: 269230080 17:34:21 INFO - PROCESS | 1680 | 17:34:24 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 17:34:25 INFO - PROCESS | 1680 | RSS: Main: 269475840 17:34:25 INFO - PROCESS | 1680 | 17:34:27 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 17:34:27 INFO - PROCESS | 1680 | RSS: Main: 270385152 17:34:27 INFO - PROCESS | 1680 | 17:34:29 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:34:30 INFO - PROCESS | 1680 | RSS: Main: 271073280 17:34:30 INFO - PROCESS | 1680 | 17:34:33 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 17:34:34 INFO - PROCESS | 1680 | RSS: Main: 271851520 17:34:34 INFO - PROCESS | 1680 | 17:34:37 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 17:34:37 INFO - PROCESS | 1680 | RSS: Main: 271695872 17:34:37 INFO - PROCESS | 1680 | 17:34:40 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 17:34:41 INFO - PROCESS | 1680 | RSS: Main: 272064512 17:34:41 INFO - PROCESS | 1680 | 17:34:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:34:43 INFO - PROCESS | 1680 | RSS: Main: 271708160 17:34:43 INFO - PROCESS | 1680 | 17:34:45 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:34:46 INFO - PROCESS | 1680 | RSS: Main: 271527936 17:34:46 INFO - PROCESS | 1680 | 17:34:48 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:34:48 INFO - PROCESS | 1680 | RSS: Main: 271757312 17:34:48 INFO - PROCESS | 1680 | 17:34:51 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:34:52 INFO - PROCESS | 1680 | RSS: Main: 271863808 17:34:52 INFO - PROCESS | 1680 | 17:34:55 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:34:56 INFO - PROCESS | 1680 | RSS: Main: 271773696 17:34:56 INFO - PROCESS | 1680 | 17:34:57 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 17:34:58 INFO - PROCESS | 1680 | RSS: Main: 272801792 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | __start_tp_report 17:34:58 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 17:34:58 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 17:34:58 INFO - PROCESS | 1680 | |i|pagename|runs| 17:34:58 INFO - PROCESS | 1680 | |0;ai-astar;115;107;115;116;116;116;113;115;117;116 17:34:58 INFO - PROCESS | 1680 | |1;audio-beat-detection;153;123;141;163;122;139;138;122;163;118 17:34:58 INFO - PROCESS | 1680 | |2;audio-dft;196;200;199;206;231;194;201;194;199;195 17:34:58 INFO - PROCESS | 1680 | |3;audio-fft;69;77;71;108;60;60;55;62;129;58 17:34:58 INFO - PROCESS | 1680 | |4;audio-oscillator;70;71;120;73;73;78;120;89;87;116 17:34:58 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;89;86;89;86;90;103;88;104;84;131 17:34:58 INFO - PROCESS | 1680 | |6;imaging-darkroom;95;95;114;123;96;99;92;136;94;117 17:34:58 INFO - PROCESS | 1680 | |7;imaging-desaturate;90;92;90;91;92;90;91;99;139;111 17:34:58 INFO - PROCESS | 1680 | |8;json-parse-financial;58;56;57;56;58;59;58;57;56;55 17:34:58 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;66;68;65;88;119;112;86;70;70;69 17:34:58 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;87;76;97;108;105;106;88;87;116;89 17:34:58 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;144;150;130;140;147;148;149;156;163;139 17:34:58 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;173;150;202;225;205;163;174;201;168;187 17:34:58 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;74;67;67;67;67;69;65;67;65;64 17:34:58 INFO - PROCESS | 1680 | __end_tp_report 17:34:58 INFO - PROCESS | 1680 | __start_cc_report 17:34:58 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1062 17:34:58 INFO - PROCESS | 1680 | __end_cc_report 17:34:58 INFO - PROCESS | 1680 | __startTimestamp1464741297997__endTimestamp 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | ------- Summary: start ------- 17:34:58 INFO - PROCESS | 1680 | Number of tests: 14 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:114.60 Median:116.00 stddev:2.88 (2.5%) stddev-sans-first:3.05 17:34:58 INFO - PROCESS | 1680 | Values: 115.0 107.0 115.0 116.0 116.0 116.0 113.0 115.0 117.0 116.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:140.00 stddev:17.07 (12.2%) stddev-sans-first:17.24 17:34:58 INFO - PROCESS | 1680 | Values: 153.0 123.0 141.0 163.0 122.0 139.0 138.0 122.0 163.0 118.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:201.50 Median:199.50 stddev:11.01 (5.5%) stddev-sans-first:11.49 17:34:58 INFO - PROCESS | 1680 | Values: 196.0 200.0 199.0 206.0 231.0 194.0 201.0 194.0 199.0 195.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:74.90 Median:61.00 stddev:24.42 (40.0%) stddev-sans-first:25.81 17:34:58 INFO - PROCESS | 1680 | Values: 69.0 77.0 71.0 108.0 60.0 60.0 55.0 62.0 129.0 58.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:73.00 stddev:21.00 (28.8%) stddev-sans-first:21.03 17:34:58 INFO - PROCESS | 1680 | Values: 70.0 71.0 120.0 73.0 73.0 78.0 120.0 89.0 87.0 116.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:87.00 stddev:14.41 (16.6%) stddev-sans-first:15.12 17:34:58 INFO - PROCESS | 1680 | Values: 89.0 86.0 89.0 86.0 90.0 103.0 88.0 104.0 84.0 131.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:106.10 Median:94.50 stddev:15.29 (16.2%) stddev-sans-first:15.68 17:34:58 INFO - PROCESS | 1680 | Values: 95.0 95.0 114.0 123.0 96.0 99.0 92.0 136.0 94.0 117.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:98.50 Median:91.00 stddev:15.67 (17.2%) stddev-sans-first:16.32 17:34:58 INFO - PROCESS | 1680 | Values: 90.0 92.0 90.0 91.0 92.0 90.0 91.0 99.0 139.0 111.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.50 stddev:1.25 (2.2%) stddev-sans-first:1.27 17:34:58 INFO - PROCESS | 1680 | Values: 58.0 56.0 57.0 56.0 58.0 59.0 58.0 57.0 56.0 55.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:81.30 Median:69.50 stddev:19.76 (28.4%) stddev-sans-first:20.17 17:34:58 INFO - PROCESS | 1680 | Values: 66.0 68.0 65.0 88.0 119.0 112.0 86.0 70.0 70.0 69.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:95.90 Median:87.00 stddev:12.48 (14.3%) stddev-sans-first:12.81 17:34:58 INFO - PROCESS | 1680 | Values: 87.0 76.0 97.0 108.0 105.0 106.0 88.0 87.0 116.0 89.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:148.50 stddev:9.19 (6.2%) stddev-sans-first:9.70 17:34:58 INFO - PROCESS | 1680 | Values: 144.0 150.0 130.0 140.0 147.0 148.0 149.0 156.0 163.0 139.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.80 Median:194.00 stddev:23.12 (11.9%) stddev-sans-first:24.13 17:34:58 INFO - PROCESS | 1680 | Values: 173.0 150.0 202.0 225.0 205.0 163.0 174.0 201.0 168.0 187.0 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:2.78 (4.2%) stddev-sans-first:1.51 17:34:58 INFO - PROCESS | 1680 | Values: 74.0 67.0 67.0 67.0 67.0 69.0 65.0 67.0 65.0 64.0 17:34:58 INFO - PROCESS | 1680 | -------- Summary: end -------- 17:34:58 INFO - PROCESS | 1680 | 17:34:58 INFO - TEST-INFO | 1680: exit 0 17:34:58 INFO - TEST-OK | kraken | took 59571ms 17:34:58 INFO - SUITE-END | took 1172s 17:34:59 INFO - Completed test suite (00:19:32) 17:34:59 INFO - javascript benchmark 17:34:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7296.882789439869, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2886.6968968258334, 3507.87722429715, 3516.5428494295747, 3530.7704999175053, 3532.9823350883244, 5781.797637082703, 5817.925795911489, 5860.179574859523, 5893.469587737108, 5911.083782013784, 5708.91530609184, 5794.913076303848, 5821.854453638661, 5867.7066146692705, 5899.911501327473, 5216.843494695166, 5237.292965449673, 5289.418164001957, 5301.6818990860675, 5362.892742145154, 5274.0, 5374.355077390699, 5402.702851343174, 5429.0227759003365, 5446.700431476267, 4378.255696531597, 5176.430592634807, 5226.346706661668, 5237.502437268454, 5314.468553144674, 4440.578145076245, 5321.73391330432, 5330.493603107738, 5332.546733527654, 5336.43967383423, 4447.577480139382, 4514.735873955297, 5156.510131537498, 5296.814611488618, 5303.8408847734245, 4308.82764689412, 5558.8604336111, 5738.225339579145, 5755.194272801811, 5853.356130825606, 6244.157038799749, 6265.4361107500545, 6281.560290779647, 6295.307516173218, 6300.716467758962, 6208.844778880519, 6221.937780622181, 6252.248291298601, 6277.058441233806, 6302.653354065525, 5937.346891841894, 6013.037913933794, 6013.729382177812, 6019.518438524908, 6092.208012958287, 6352.841178970562, 6390.840228994312, 6409.1668083149825, 6418.74325027003, 6441.323661015525, 6112.419320164578, 6168.414000669929, 6196.411340922605, 6258.812235632939, 6286.968565157145, 6246.781362652286, 6302.716377763011, 6315.4947604192475, 6327.335629758809, 6341.524385671093, 5643.604947653625, 5859.062549991981, 6182.876342473215, 6281.59169653971, 6302.432781049728, 6178.845528861724, 6283.214598175228, 6299.6850157491945, 6301.558890877686, 6309.810705678837, 4993.251012348177, 5011.6241281904, 5016.02187573427, 5034.270030845507, 5050.393952725696, 4483.21543729846, 4485.932711009337, 4505.797239124247, 4512.029913568533, 4521.412216411845, 4801.375821143299, 5842.065269556851, 5958.970205148947, 5994.820155395345, 6038.5169186465855, 6240.282367527701, 6241.126242326078, 6269.592476489013, 6270.184875966186, 6284.37156284377, 5962.165296858443, 5964.314103878023, 5972.3125338151285, 6003.459688628044, 6045.486133678599, 5347.86630334237, 5495.093309603871, 5511.5039646432015, 5522.0336441122645, 5541.085720856014, 5444.891102178013, 6551.63965981866, 6558.508111891627, 6574.967125164344, 6593.505487088391, 6267.091271765659, 6299.622022678654, 6304.5271604628915, 6304.6847657617855, 6306.432421082125, 6279.811605651837, 6412.871742565029, 6421.5825971311715, 6465.965445528693, 6561.40947314744, 6249.687515624109, 6278.591891527036, 6282.4974002080635, 6292.244930608356, 6299.30707622152, 5272.6309158358545, 6264.2796078451565, 6279.497640188682, 6302.684865756785, 6302.842428939313, 6044.274687037584, 6081.878362432638, 6102.368068311607, 6129.295131059986, 6231.2522497300615, 6163.106349579376, 6177.351378105414, 6216.564840461125, 6231.1276421300145, 6315.08431277453, 5457.608309880955, 6147.477464415575, 6161.969190154021, 6162.168107305456, 6202.100695399232, 6098.280951554018, 6145.877082458236, 6149.385061493815, 6178.443940045418, 6270.372962703693, 6098.778248023657, 6128.203333566607, 6144.17053697763, 6150.630962142286, 6150.630962142286], "value": 5750.671476766871, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.6258025190981, 516.3004129404654, 523.756453249239, 532.2043544900374, 558.5643198305323, 34720.958371248875, 34793.65206347942, 41200.0, 42112.736617901435, 42146.0, 41314.58685413145, 42952.14095718084, 43676.56323436765, 43689.126217475634, 43839.56160438395, 9416.905830941689, 9469.100435458655, 9596.184324332406, 9682.75793105171, 9690.418574885529, 9572.904270957306, 9653.0, 9653.02113066823, 9693.515324233795, 9703.563339649732, 9619.134277914985, 9689.51552422376, 9699.806003879918, 9706.174975127122, 9716.368436051669, 9592.472414017226, 9598.328117031811, 9672.75818104546, 9692.660756873476, 9700.805983880318, 9405.247580193569, 9455.90544094564, 9456.101670341306, 9528.333016688835, 9548.092931171528, 40344.39483407751, 40418.59581404178, 40853.59146408528, 40881.79559102185, 40936.79531602353, 3439.8280085995852, 3493.8427770750377, 3584.791611507712, 3590.120420496995, 3735.1035751419736, 32201.677983220103, 32481.67518324834, 32612.510812337834, 33195.50206746901, 33344.33311333784, 3253.8210398428077, 3293.4236508610898, 3323.8670453181844, 3404.0468668772783, 3421.8973430797114, 504351.0, 504745.0, 510797.0, 511131.0, 518802.0, 35099.298014039574, 64035.67982160152, 64158.0, 64406.67796660987, 64421.0, 35877.641223587954, 35981.46027809585, 36798.44802327967, 36890.077748056516, 36980.0, 15091.396344146111, 15226.467073652591, 15387.461438849587, 15429.537113886601, 15474.845251547566, 12910.483580656857, 12912.548060817826, 13034.543790967271, 13066.934665326802, 13067.215967042008, 6700.497462690318, 7779.72770953014, 7947.841043179104, 8001.839963200703, 8009.759707208793, 3255.088575198948, 3256.47896336585, 3293.9176520587175, 3318.622771549828, 3374.905367175344, 843.3759018326517, 912.7626817027488, 1041.265907535186, 1051.3218973762034, 1055.6147006342783, 29859.552106718416, 30665.540016899766, 30745.077647670158, 31165.844170779, 31209.687903120677, 1011.1657882247175, 1019.1184625299063, 1087.5377964365114, 1102.4763237462303, 1130.5133749119882, 15827.129507877178, 16172.110533920511, 16247.0, 16883.32466701318, 17149.971001739934, 671856.0, 680027.0, 683093.0, 688759.0, 688784.0, 61730.0, 62158.892055397046, 62252.06621900675, 62329.0, 62365.0, 1352.8579499152645, 1355.9932200338935, 1398.8461269260374, 1401.6075498860334, 1402.3198748606917, 27892.860535697193, 31828.5225721619, 31991.520127197644, 32587.18532036718, 32685.673143268265, 59688.403115970024, 60129.0, 60503.0, 60573.69713151406, 60593.0, 533758.6624133709, 534593.0, 593863.0, 604094.0, 608372.0, 45241.77379113215, 45413.54586454093, 45534.861628459556, 46145.30782038205, 46333.0, 48806.75596621994, 49024.26463602977, 49769.00923963001, 49994.00011999813, 51377.7431112842, 40279.59720402758, 40703.0, 41999.58000419957, 42178.789106054275, 43468.782656086514, 41742.79128604459, 42396.78801605972, 43605.56394436142, 43692.0, 43907.0, 36489.270214596094, 42434.787826060674, 42762.0, 43059.78470107629, 43178.78410608052, 43276.78361608172, 43379.566204338815, 43505.129897402505, 43623.0, 43916.78041609772, 23186.536269274857, 26624.600630990164, 27704.86147569249, 27769.722302776714, 27795.861020694767], "value": 19733.34131745462, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.1390950442902, 380.8343370633773, 387.9592642772509, 398.4600865826803, 406.29507803960126, 18488.630227395442, 19191.32830350938, 19752.604947901033, 20360.69458958113, 21322.89338553307, 19775.901120494414, 19917.40247792565, 20276.59446811063, 20467.48831279219, 20821.0, 5645.15322701594, 5724.427557244274, 5962.284525856903, 5962.522998160159, 6008.218931538916, 5815.273090863642, 5861.941380586203, 5873.823785286449, 5917.970410147943, 5952.345242023374, 5752.2234498342605, 5793.131030345461, 5795.2466179396615, 5877.235959325303, 5930.080837470177, 5751.712414379264, 5918.704064796786, 5920.881582368372, 5922.88154236915, 5932.822015339547, 5075.467075957008, 5115.514026167508, 5135.6148288878485, 5142.794288228466, 5153.304303918961, 25203.0, 25344.0, 25415.61876571853, 25585.23244302674, 25779.742202577923, 2232.8325375596896, 2258.153192552793, 2318.8260880434036, 2617.41108250644, 2654.933626659349, 13503.59489215325, 14317.926155538273, 14853.331600078023, 15388.30752616135, 15832.20838958059, 1564.6244901223738, 1587.9920600396924, 1596.696627640756, 1638.6394993101499, 1711.0674682298177, 90410.54794726051, 109464.4526777369, 109988.45005775, 111441.44279278551, 112051.0, 10387.766630174548, 10614.991575800286, 10692.305000174963, 10976.12191024716, 11062.612808551663, 13393.0, 13397.196168229937, 13482.0, 13504.122232054886, 13517.32413379327, 8399.328053755686, 8575.99299268916, 8587.184217499327, 8645.394822362374, 8679.826403471896, 6350.745970161234, 8207.891934588753, 8256.091829898707, 8458.773477845802, 8787.077356877437, 5681.460261275172, 6594.07682924391, 6596.175478065242, 6596.80209593713, 6682.799516014527, 1845.344902559599, 1853.2031226572433, 1870.5136664467336, 1871.737956686065, 1884.4723477426342, 902.2962089570145, 972.7957129002847, 1006.5118417567473, 1034.2656713733334, 1066.274933045521, 19561.0, 20763.065662045243, 20821.0, 21878.45303867416, 21888.343349699535, 878.4246318661287, 908.4413085952092, 1032.4321623107262, 1035.2804800663616, 1072.8980746829038, 10586.153107751361, 11004.404361367557, 11023.779524409627, 11029.117670586334, 11062.05972492331, 575752.0, 578612.0, 611645.0, 615753.0, 621309.0, 112399.82602348742, 137409.3129534366, 143899.0, 145353.27323363317, 147082.26458867636, 1032.2155162076876, 1057.1912486947492, 1067.2155965365296, 1075.9731006724894, 1077.3105212663902, 17221.741673875153, 18246.72629910579, 18326.99201543928, 18482.81517184811, 18485.630287394444, 53083.20375194452, 54498.727506362215, 55160.44839551553, 56145.43854561402, 56204.15693764515, 62697.17862696099, 74968.25031749831, 79143.81284280625, 83024.58487707523, 84992.72510912217, 5198.844034678966, 6291.496680265476, 8057.597120143852, 8123.593820309079, 8394.664213431395, 10455.68632941013, 13122.737545249232, 13202.405891735087, 13302.334883255991, 13408.262545560088, 11187.944060279646, 11228.55085796559, 11462.197646165023, 11506.252093613888, 11564.421778910853, 10460.529276182742, 11195.664130076111, 11251.718707032389, 11291.710821554381, 11336.773264534499, 8038.718644847402, 11100.833487497855, 11468.541258349906, 11642.592509262135, 11693.123015774021, 6834.692438840363, 7512.399008079231, 7544.773656790305, 7579.431542634215, 7773.378129749493, 10121.342112762646, 10406.323588966692, 11453.599124030952, 11603.477843496894, 11619.0], "value": 9647.728002694612, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.7464693678608, 1554.7978762760843, 1780.8397244248024, 1894.08137053529, 1921.5196200949763, 8158.265756081966, 8711.564421778901, 8973.282137429007, 9329.160375566198, 9357.344985850985, 8847.247983921374, 8925.910740892588, 9065.954670226656, 9077.773055673611, 9080.636774529028, 2352.541254455386, 2373.9881300593474, 2379.5954687703047, 2383.1539803369815, 2386.3914701751028, 2365.1840115160244, 2376.1089591403224, 2383.559041577305, 2413.348395933097, 2423.6485709572103, 2343.179887039531, 2364.798992085675, 2367.9052837886466, 2417.9637305440433, 2432.62294344376, 2360.5514952159033, 2374.2402431222017, 2377.084822343393, 2387.7015373078366, 2416.9879150604133, 2375.4180225844607, 2377.762223777626, 2400.1599440195964, 2409.5903696371483, 2414.1429792423614, 8989.955050224773, 9033.548322583845, 9039.68361107358, 9045.321600879895, 9049.457032578066, 5162.354705661793, 5403.567714582863, 5454.8909021819345, 5477.123660214346, 5566.721663916788, 2932.618759561243, 3609.097725568608, 3724.627537246281, 3744.1762812181278, 3784.0, 1087.597588892107, 1103.0623969625833, 1111.149970272742, 1118.4128332625355, 1125.5610311978253, 11913.88086119145, 12145.574904878287, 12147.635570932885, 12158.088143389281, 12159.270443773403, 4775.498572649891, 4777.092352453093, 4909.754512274443, 4922.4339200992345, 4932.112219800399, 9935.453550054817, 10190.745231369126, 10207.846882296772, 10231.693049208536, 10233.846492302622, 5349.1976203569775, 5362.249285100089, 5443.237946687467, 5454.400015998237, 5477.616566840283, 4498.347739577808, 4868.902621947541, 4982.302477653131, 5024.346834911438, 5029.119904016856, 3986.5016872890887, 4063.918721625551, 4078.8164532596106, 4105.4252404663375, 4122.381642753611, 1108.7948729485072, 1344.3547097393307, 1349.8380194376737, 1362.6661467940419, 1366.6538459617268, 759.8746206875803, 945.3288165402504, 956.6747305916022, 969.5249327829313, 975.1857199238574, 6076.453119219292, 6130.264368275835, 6187.917114504944, 6200.472959798378, 6226.782062627786, 412.75965723002787, 424.12629982236075, 437.5362116156884, 445.2097526889797, 453.975031373271, 4080.8775736727944, 4097.364908439196, 4105.507339119325, 4128.298189307825, 4161.583841615875, 10389.584416623251, 10694.62568810088, 10823.945880270549, 10824.296420732628, 10829.0, 10680.038796508352, 10695.411752353539, 10760.461976901124, 10765.515551800188, 10784.94607526973, 7082.654295683804, 8239.546824924686, 8258.42191046633, 8304.8339033219, 8342.66629334832, 6219.968900155471, 6227.719752611053, 6300.369963003663, 6306.590071645328, 6350.872982540416, 9890.851637225303, 10069.244806639677, 10308.175345972455, 10319.587216511256, 10346.586136554453, 10263.538140783534, 10688.786224275627, 10706.625268115578, 10724.356538607708, 10744.086752626114, 7150.284971502808, 7592.0, 7710.845783084419, 7788.571628560484, 7873.291403773688, 8484.066752657327, 8624.137586241326, 8668.176523230282, 8731.650733970824, 8783.868241976248, 7528.209537998431, 7637.274458926393, 7696.7306144284685, 7709.113451953097, 7776.572288524186, 7491.70033198666, 7557.622118894144, 7573.35626471756, 7678.347340475899, 7739.92260077392, 7181.461390395637, 7267.127944646676, 7278.344948954619, 7280.126384833854, 7290.671919763729, 5008.073506401327, 5132.666376685504, 5144.022635699205, 5223.190405487156, 5229.0, 5224.0, 5249.947500525099, 5257.5005374489365, 5290.550303224269, 5302.867428314323], "value": 4619.445829059472, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.975441964643, 315.057976649817, 320.39445448103083, 325.451614030359, 326.52164578891916, 4269.487661480619, 5065.4934506549325, 5264.526192642661, 5558.6942718150485, 5690.715464226788, 4711.623070154388, 4747.05058988202, 5429.782808687658, 5527.834164975057, 5559.444055594438, 3322.401967645823, 3912.139329347553, 4226.281532139536, 4238.618524332809, 4247.936280955788, 3685.834137463807, 3783.959411161925, 4239.9576004240025, 4257.169851878885, 4274.487061552602, 4136.255474014676, 4177.853775117872, 4180.581941805825, 4180.745776267123, 4224.767637779936, 3531.7704349217347, 3689.6863766579604, 4210.115875666083, 4227.252286014084, 4230.259704551707, 3318.6891177984803, 3399.507071474647, 3674.4488326751202, 3683.631636836322, 3699.5560532735976, 4915.852524424273, 5002.574781143607, 5131.229725744613, 5257.316548848626, 5387.81142660005, 4487.8429254976245, 4854.757262136879, 4864.7324397158145, 4896.975515122437, 4936.80252789888, 5155.097857874857, 5884.617499862496, 5886.470217680387, 5915.763369465216, 5930.436608522184, 3786.9621303786885, 4088.325426304656, 4090.8159132839096, 4100.261952848486, 4131.5248746393945, 5911.379305172938, 6178.536609754242, 6178.598391104564, 6185.577312221738, 6231.221097362784, 3459.7405194610506, 3837.213371258885, 4046.9595304046584, 4105.568915263914, 4138.8551400700835, 4051.817668204938, 4149.522804877417, 4168.6665066794585, 4175.394567787715, 4185.121124563815, 3635.527381440396, 4547.272436410225, 4657.976710116428, 4693.9765301173275, 4787.0, 4446.866594002185, 5420.4850539198715, 5592.916106258409, 5862.5896187267335, 5882.382349853289, 1374.1311815930326, 1399.6710772968345, 1427.1365823676733, 1450.2893582144673, 1453.9604183009199, 818.6520728690402, 828.9182616685163, 874.8993865705526, 906.945583264993, 912.9224015958586, 4113.9509425097, 4184.079502509443, 4218.957810421856, 4470.418845550058, 4640.0, 4617.58441740245, 4643.883902902387, 4740.19416699162, 4946.579540739005, 5139.460356662496, 4229.555896630871, 4232.2647714436935, 4307.353896915425, 4560.426652804777, 4586.587207151306, 2500.6124050771796, 2569.717331093578, 2731.003183837885, 2737.2891942535916, 2743.5596311936392, 4638.304254361873, 4859.343988561499, 4877.926831097537, 5180.156553622375, 5396.219247648267, 4373.479349123986, 4405.185040767404, 4509.519764129715, 4625.8843528911375, 5167.10402535027, 4116.9090191099385, 4700.918788678555, 4792.400949881265, 4924.704517728947, 5391.086164411656, 4237.745735255895, 4406.845760398371, 4728.739919304471, 5062.468765617192, 5066.6440105351185, 4689.507601701908, 5047.343845300087, 5096.108181068224, 5108.284840122312, 5173.292813371503, 4989.476105009067, 5764.7405866736935, 6419.0, 6419.903701444576, 6432.292447830829, 3441.2773317603583, 3657.7622454541433, 3934.193490334532, 4006.2973236375005, 4015.799210039545, 5524.66851988882, 5605.947213490864, 5852.385499522659, 5859.765609375577, 6128.816135515942, 4919.0, 4925.753712314442, 5205.609579281493, 5868.413158684269, 5881.294604481815, 5386.757595908115, 5922.407759224043, 5928.436798504135, 5944.3164036136395, 5954.583179177416, 5026.52248036436, 5117.053345131163, 5261.89476210466, 5680.375158732453, 5758.769649213985, 5301.970502418267, 5681.630694004876, 5911.290645122613, 5932.584719069796, 5934.881302374015, 5377.596680248918, 5560.972195138998, 5569.33168019824, 5610.43895610452, 5836.505890464404], "value": 4033.1991734103653, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.4147328454026, 720.9639518024101, 777.1373775109631, 777.6422845491073, 856.4304737349663, 14734.410623575057, 15260.847391526082, 15416.460423885166, 15426.922865385686, 16090.678186436293, 15975.84024159758, 15990.360385584563, 15995.040297582123, 16010.119443430607, 16019.759703604455, 694.5728377048105, 885.4952676974111, 886.2599729226104, 896.4352457951514, 897.9102089791002, 873.3973558244822, 887.8313120507115, 888.9288856891433, 898.6989358564889, 899.5996781432265, 809.0, 938.1181689212153, 955.4362925873733, 955.7132860141965, 956.669948867643, 925.6806401791405, 945.1588086602928, 946.4463288975958, 948.7011591348737, 953.2421724728857, 710.662435343213, 711.3491155592626, 711.5055036749461, 713.1264201353353, 713.2510863593258, 14618.70762584753, 14690.338934747962, 14811.555653330417, 14814.259287035606, 14842.406303747837, 13385.531506397327, 13478.989075819352, 13480.595582132453, 13519.391627376694, 13553.39009744564, 11434.885651143466, 11718.622004438359, 11836.420901222662, 11909.761804763943, 12154.756904861943, 3227.677232276777, 3290.24794047834, 3771.962280377216, 3773.1699026214187, 3773.52830896138, 80255.59872200602, 80257.0, 81699.0, 81738.0, 81822.0, 10522.473876306154, 10523.210759192938, 10630.149588032938, 10677.786444271227, 11015.889841101487, 20481.2831550895, 20752.48118797012, 20940.267090651752, 20944.685829712565, 21005.15979360839, 7706.267904549059, 7754.9224507755325, 7850.332721718603, 7911.762647120596, 8014.438989270695, 4453.0, 4460.487043989918, 4470.307102399133, 4494.797734101973, 4594.196015697306, 3494.965050349464, 3580.409232476612, 3598.7300952428673, 3603.1532589841368, 3608.314420260142, 582.9009068458372, 614.5114633866087, 677.3801971196399, 683.3712984054681, 699.1191099214924, 609.411917499615, 687.8796210663114, 696.4115322552435, 717.5013365710885, 717.7452004538313, 7792.2986931176465, 8091.878621820678, 9342.392744471588, 9412.529373531295, 9433.858492122623, 492.7683988525459, 637.611057255074, 648.8410339466861, 661.8411581220569, 674.5008693566725, 4348.499922508889, 4575.0, 4855.32025516428, 5002.74986250686, 5011.173156429221, 93940.53029734807, 96016.51991739997, 97762.0, 98465.01534984559, 98516.50741746389, 84951.57524212463, 85038.14961850426, 85953.0, 86038.13961860427, 86795.56602216949, 76401.0, 78188.60905695434, 78753.60623196962, 78936.0, 79164.60417697874, 9415.717528474157, 9539.427634341962, 9705.9029409705, 9755.658551950932, 9763.414195148314, 48365.7908552275, 48478.5152148474, 48889.266661000824, 48936.75531622319, 49534.256986144515, 52778.0, 67328.0, 67870.3212967864, 68102.31897681158, 68355.65822170857, 12920.806187907001, 12967.01877759398, 13020.41408136655, 13039.674008149872, 13333.266670333225, 14372.0, 14373.784393234318, 14554.345054472362, 14590.781138283146, 15106.320215590544, 12372.434410802267, 12654.936725316315, 13469.46122155142, 13746.96897732694, 13900.668368208413, 11071.667849964513, 11636.592719254786, 11878.346690932081, 12222.388880555758, 12470.0, 12986.110076526164, 13142.868571314548, 13169.0, 13286.933565332112, 13318.800217996544, 9696.430445931692, 10722.356658600509, 11423.485943132746, 11455.484503197209, 11475.598354057567, 11191.832122518332, 11254.831177532507, 11498.25261358009, 11506.07951363872, 11506.367149806842], "value": 7399.367945935197, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 107.0, 115.0, 116.0, 116.0, 116.0, 113.0, 115.0, 117.0, 116.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 141.0, 163.0, 122.0, 139.0, 138.0, 122.0, 163.0, 118.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 200.0, 199.0, 206.0, 231.0, 194.0, 201.0, 194.0, 199.0, 195.0], "value": 201.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 71.0, 108.0, 60.0, 60.0, 55.0, 62.0, 129.0, 58.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 120.0, 73.0, 73.0, 78.0, 120.0, 89.0, 87.0, 116.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 90.0, 103.0, 88.0, 104.0, 84.0, 131.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 114.0, 123.0, 96.0, 99.0, 92.0, 136.0, 94.0, 117.0], "value": 106.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 90.0, 91.0, 92.0, 90.0, 91.0, 99.0, 139.0, 111.0], "value": 98.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 57.0, 56.0, 58.0, 59.0, 58.0, 57.0, 56.0, 55.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 65.0, 88.0, 119.0, 112.0, 86.0, 70.0, 70.0, 69.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 76.0, 97.0, 108.0, 105.0, 106.0, 88.0, 87.0, 116.0, 89.0], "value": 95.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 150.0, 130.0, 140.0, 147.0, 148.0, 149.0, 156.0, 163.0, 139.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 150.0, 202.0, 225.0, 205.0, 163.0, 174.0, 201.0, 168.0, 187.0], "value": 184.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 67.0, 67.0, 67.0, 67.0, 69.0, 65.0, 67.0, 65.0, 64.0], "value": 67.2, "unit": "score"}]}]} 17:34:59 INFO - Return code: 0 17:34:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:34:59 ERROR - # TBPL SUCCESS # 17:34:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:34:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:34:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:34:59 INFO - 2016-05-31 17:34:59.305 screenresolution[1685:12535] starting screenresolution argv=screenresolution get 17:34:59 INFO - 2016-05-31 17:34:59.307 screenresolution[1685:12535] Display 0: 1600x1200x32@60 17:34:59 INFO - 2016-05-31 17:34:59.313 screenresolution[1686:12538] starting screenresolution argv=screenresolution list 17:34:59 INFO - Available Modes on Display 0 17:34:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:34:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:34:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:34:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:34:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:34:59 INFO - Intel Iris: 17:34:59 INFO - Chipset Model: Intel Iris 17:34:59 INFO - Type: GPU 17:34:59 INFO - Bus: Built-In 17:34:59 INFO - VRAM (Dynamic, Max): 1536 MB 17:34:59 INFO - Vendor: Intel (0x8086) 17:34:59 INFO - Device ID: 0x0a2e 17:34:59 INFO - Revision ID: 0x0009 17:34:59 INFO - Displays: 17:34:59 INFO - Display: 17:34:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:34:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:34:59 INFO - Display Serial Number: 1600x1200 60 17:34:59 INFO - Main Display: Yes 17:34:59 INFO - Mirror: Off 17:34:59 INFO - Online: Yes 17:34:59 INFO - Rotation: Supported 17:34:59 INFO - Return code: 0 17:34:59 INFO - Running post-action listener: _resource_record_post_action 17:34:59 INFO - ##### 17:34:59 INFO - ##### Finished run-tests step (success) 17:34:59 INFO - ##### 17:34:59 INFO - Running post-run listener: _resource_record_post_run 17:34:59 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 31456768; Write bytes: 700065280; Read time: 322; Write time: 1915 17:34:59 INFO - TinderboxPrint: CPU usage
54.7% 17:34:59 INFO - TinderboxPrint: I/O read bytes / time
31,456,768 / 322 17:34:59 INFO - TinderboxPrint: I/O write bytes / time
700,065,280 / 1,915 17:34:59 INFO - TinderboxPrint: CPU idle
2,190.4 (45.3%) 17:34:59 INFO - TinderboxPrint: CPU system
974.6 (20.1%) 17:34:59 INFO - TinderboxPrint: CPU user
1,672.7 (34.6%) 17:34:59 INFO - TinderboxPrint: Swap in / out
426,577,920 / 0 17:34:59 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 384766464; Write bytes: 379551232; Read time: 29890; Write time: 705 17:34:59 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 30518784; Write bytes: 297506816; Read time: 307; Write time: 1163 17:35:00 INFO - Running post-run listener: _upload_blobber_files 17:35:00 INFO - Blob upload gear active. 17:35:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:35:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:35:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:35:00 INFO - (blobuploader) - INFO - Open directory for files ... 17:35:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:35:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:35:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:01 INFO - (blobuploader) - INFO - Done attempting. 17:35:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:35:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:35:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:02 INFO - (blobuploader) - INFO - Done attempting. 17:35:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:35:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:35:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:05 INFO - (blobuploader) - INFO - Done attempting. 17:35:05 INFO - (blobuploader) - INFO - Iteration through files over. 17:35:05 INFO - Return code: 0 17:35:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:35:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:35:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/391f2cd35dae050bacdbc6d80ed0fa9378c1eb4b50d3b94719799fec60db44650da316ca95a38a25863139c32a19254b4f358313b6b87333101daf3d5355bd17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5e6df20ec3e2ed1adc83b5ea720672b4bf571ba9a381bd8f686e4954efcdfa55ef8bd024d00796b627b4763350168a91859764505bd38db215ff14796e303eba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bdf921a2d7d75127e479aa8938addceff2082a5d1c27c93d16434236ad94fe0a7500e755ea5c46e6f00ec2cced14e49c889c49fa9c7d4870f9616d9782c19127"} 17:35:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:35:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:35:05 INFO - Contents: 17:35:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/391f2cd35dae050bacdbc6d80ed0fa9378c1eb4b50d3b94719799fec60db44650da316ca95a38a25863139c32a19254b4f358313b6b87333101daf3d5355bd17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5e6df20ec3e2ed1adc83b5ea720672b4bf571ba9a381bd8f686e4954efcdfa55ef8bd024d00796b627b4763350168a91859764505bd38db215ff14796e303eba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bdf921a2d7d75127e479aa8938addceff2082a5d1c27c93d16434236ad94fe0a7500e755ea5c46e6f00ec2cced14e49c889c49fa9c7d4870f9616d9782c19127"} 17:35:05 INFO - Running post-run listener: copy_logs_to_upload_dir 17:35:05 INFO - Copying logs to upload dir... 17:35:05 INFO - mkdir: /builds/slave/test/build/upload/logs 17:35:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.266877 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-31 17:35:05.107155) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 17:35:05.110889) ========= 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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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/391f2cd35dae050bacdbc6d80ed0fa9378c1eb4b50d3b94719799fec60db44650da316ca95a38a25863139c32a19254b4f358313b6b87333101daf3d5355bd17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5e6df20ec3e2ed1adc83b5ea720672b4bf571ba9a381bd8f686e4954efcdfa55ef8bd024d00796b627b4763350168a91859764505bd38db215ff14796e303eba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bdf921a2d7d75127e479aa8938addceff2082a5d1c27c93d16434236ad94fe0a7500e755ea5c46e6f00ec2cced14e49c889c49fa9c7d4870f9616d9782c19127"} build_url:https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011301 build_url: 'https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/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/391f2cd35dae050bacdbc6d80ed0fa9378c1eb4b50d3b94719799fec60db44650da316ca95a38a25863139c32a19254b4f358313b6b87333101daf3d5355bd17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5e6df20ec3e2ed1adc83b5ea720672b4bf571ba9a381bd8f686e4954efcdfa55ef8bd024d00796b627b4763350168a91859764505bd38db215ff14796e303eba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bdf921a2d7d75127e479aa8938addceff2082a5d1c27c93d16434236ad94fe0a7500e755ea5c46e6f00ec2cced14e49c889c49fa9c7d4870f9616d9782c19127"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 17:35:05.143178) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 17:35:05.143524) ========= 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.z6Z9Wkfdy3/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.vppvHXcBCy/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.005092 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 17:35:05.195253) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 17:35:05.196019) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 17:35:05.196842) ========= ========= Total master_lag: 0.18 =========