builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0306 starttime: 1464807012.97 results: success (0) buildid: 20160601104449 builduid: 1cb14c503db440eab50d58ea7a0dd95e revision: 359674b9970a98e42c97184e9d7c59c89bac06d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:12.967252) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:12.967778) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:12.968056) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:12.991588) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:12.991942) ========= 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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-06-01 11:50:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.3M=0.001s 2016-06-01 11:50:13 (16.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.474111 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:13.508551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:13.514941) ========= 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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.029256 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:13.582747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 11:50:13.583345) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 359674b9970a98e42c97184e9d7c59c89bac06d7 --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 359674b9970a98e42c97184e9d7c59c89bac06d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zkJWAjUSUm/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.rlw0uKJ90q/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-06-01 11:50:13,664 truncating revision to first 12 chars 2016-06-01 11:50:13,664 Setting DEBUG logging. 2016-06-01 11:50:13,664 attempt 1/10 2016-06-01 11:50:13,664 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/359674b9970a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 11:50:15,144 unpacking tar archive at: fx-team-359674b9970a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.766534 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 11:50:15.370697) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:15.371028) ========= script_repo_revision: 359674b9970a98e42c97184e9d7c59c89bac06d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:15.371488) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:15.371777) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 11:50:15.388331) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-06-01 11:50:15.388826) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zkJWAjUSUm/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.rlw0uKJ90q/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:50:15 INFO - MultiFileLogger online at 20160601 11:50:15 in /builds/slave/test 11:50:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:50:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:50:15 INFO - {'append_to_log': False, 11:50:15 INFO - 'base_work_dir': '/builds/slave/test', 11:50:15 INFO - 'blob_upload_branch': 'Fx-Team', 11:50:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:50:15 INFO - 'branch': 'Fx-Team', 11:50:15 INFO - 'buildbot_json_path': 'buildprops.json', 11:50:15 INFO - 'config_files': ('talos/mac_config.py',), 11:50:15 INFO - 'default_actions': ('clobber', 11:50:15 INFO - 'read-buildbot-config', 11:50:15 INFO - 'download-and-extract', 11:50:15 INFO - 'populate-webroot', 11:50:15 INFO - 'create-virtualenv', 11:50:15 INFO - 'install', 11:50:15 INFO - 'run-tests'), 11:50:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:50:15 INFO - 'download_minidump_stackwalk': True, 11:50:15 INFO - 'download_symbols': 'ondemand', 11:50:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:50:15 INFO - 'tooltool.py': '/tools/tooltool.py', 11:50:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:50:15 INFO - '/tools/misc-python/virtualenv.py')}, 11:50:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:50:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:50:15 INFO - 'installer_path': 'installer.exe', 11:50:15 INFO - 'log_level': 'info', 11:50:15 INFO - 'log_name': 'talos', 11:50:15 INFO - 'log_to_console': True, 11:50:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:50:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:50:15 INFO - 'opt_config_files': (), 11:50:15 INFO - 'pip_index': False, 11:50:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:50:15 INFO - 'cmd': ('bash', 11:50:15 INFO - '-c', 11:50:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:50:15 INFO - 'enabled': True, 11:50:15 INFO - 'halt_on_failure': False, 11:50:15 INFO - 'name': 'check_screen_resolution'},), 11:50:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:50:15 INFO - 'cmd': ('bash', 11:50:15 INFO - '-c', 11:50:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:50:15 INFO - 'enabled': True, 11:50:15 INFO - 'halt_on_failure': False, 11:50:15 INFO - 'name': 'check_screen_resolution'},), 11:50:15 INFO - 'run_cmd_checks_enabled': True, 11:50:15 INFO - 'sps_profile': False, 11:50:15 INFO - 'sps_profile_interval': 0, 11:50:15 INFO - 'suite': 'dromaeojs', 11:50:15 INFO - 'system_bits': '32', 11:50:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:50:15 INFO - 'title': 't-yosemite-r7-0306', 11:50:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:50:15 INFO - 'use_talos_json': True, 11:50:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:50:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:50:15 INFO - 'work_dir': 'build'} 11:50:15 INFO - ##### 11:50:15 INFO - ##### Running clobber step. 11:50:15 INFO - ##### 11:50:15 INFO - Running pre-action listener: _resource_record_pre_action 11:50:15 INFO - Running main action method: clobber 11:50:15 INFO - rmtree: /builds/slave/test/build 11:50:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:50:17 INFO - Running post-action listener: _resource_record_post_action 11:50:17 INFO - ##### 11:50:17 INFO - ##### Finished clobber step (success) 11:50:17 INFO - ##### 11:50:17 INFO - ##### 11:50:17 INFO - ##### Running read-buildbot-config step. 11:50:17 INFO - ##### 11:50:17 INFO - Running pre-action listener: _resource_record_pre_action 11:50:17 INFO - Running main action method: read_buildbot_config 11:50:17 INFO - Using buildbot properties: 11:50:17 INFO - { 11:50:17 INFO - "project": "", 11:50:17 INFO - "product": "firefox", 11:50:17 INFO - "script_repo_revision": "production", 11:50:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:50:17 INFO - "repository": "", 11:50:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:50:17 INFO - "buildid": "20160601104449", 11:50:17 INFO - "pgo_build": "False", 11:50:17 INFO - "basedir": "/builds/slave/test", 11:50:17 INFO - "buildnumber": 740, 11:50:17 INFO - "slavename": "t-yosemite-r7-0306", 11:50:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:50:17 INFO - "platform": "yosemite_r7", 11:50:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:50:17 INFO - "branch": "fx-team", 11:50:17 INFO - "repo_path": "integration/fx-team", 11:50:17 INFO - "revision": "359674b9970a98e42c97184e9d7c59c89bac06d7", 11:50:17 INFO - "stage_platform": "macosx64", 11:50:17 INFO - "builduid": "1cb14c503db440eab50d58ea7a0dd95e", 11:50:17 INFO - "slavebuilddir": "test" 11:50:17 INFO - } 11:50:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:50:17 INFO - Running post-action listener: _resource_record_post_action 11:50:17 INFO - ##### 11:50:17 INFO - ##### Finished read-buildbot-config step (success) 11:50:17 INFO - ##### 11:50:17 INFO - ##### 11:50:17 INFO - ##### Running download-and-extract step. 11:50:17 INFO - ##### 11:50:17 INFO - Running pre-action listener: _resource_record_pre_action 11:50:17 INFO - Running main action method: download_and_extract 11:50:17 INFO - mkdir: /builds/slave/test/build/tests 11:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:17 INFO - https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:50:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:50:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:50:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:50:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 11:50:26 INFO - Downloaded 1199 bytes. 11:50:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:50:26 INFO - Using the following test package requirements: 11:50:26 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:50:26 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:50:26 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:50:26 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:50:26 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:50:26 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:50:26 INFO - u'jsshell-mac.zip'], 11:50:26 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:50:26 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:50:26 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:50:26 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:50:26 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:50:26 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:50:26 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:50:26 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:50:26 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:50:26 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:50:26 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:50:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:50:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:26 INFO - https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:50:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:50:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:50:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:50:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:50:31 INFO - Downloaded 18696435 bytes. 11:50:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:50:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:50:35 INFO - Return code: 0 11:50:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:35 INFO - https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:50:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:50:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:50:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:50:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:50:38 INFO - Downloaded 18696435 bytes. 11:50:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:50:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:50:42 INFO - Return code: 0 11:50: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')]} 11:50:42 INFO - https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:50:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:50:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:50:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:50:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:50:43 INFO - Downloaded 10834055 bytes. 11:50:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:50:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:50:43 INFO - Return code: 0 11:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:43 INFO - https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:50:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:50:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:50:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:50:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 11:50:49 INFO - Downloaded 111051861 bytes. 11:50:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:50:49 INFO - mkdir: /builds/slave/test/properties 11:50:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:50:49 INFO - Writing to file /builds/slave/test/properties/build_url 11:50:49 INFO - Contents: 11:50:49 INFO - build_url:https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:50:50 INFO - Running post-action listener: _resource_record_post_action 11:50:50 INFO - Running post-action listener: set_extra_try_arguments 11:50:50 INFO - ##### 11:50:50 INFO - ##### Finished download-and-extract step (success) 11:50:50 INFO - ##### 11:50:50 INFO - ##### 11:50:50 INFO - ##### Running populate-webroot step. 11:50:50 INFO - ##### 11:50:50 INFO - Running pre-action listener: _resource_record_pre_action 11:50:50 INFO - Running main action method: populate_webroot 11:50:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:50:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:50:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:50:50 INFO - '--tppagecycles', 11:50:50 INFO - '7'], 11:50:50 INFO - 'tests': ['tsvgm']}}, 11:50:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:50:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:50:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:50:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:50:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:50 INFO - 'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:50:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:50:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:50 INFO - 'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['damp', 'tps']}, 11:50:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:50 INFO - 'tests': ['damp', 'tps']}, 11:50:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['dromaeo_dom']}, 11:50:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:50:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['a11yr', 11:50:50 INFO - 'ts_paint', 11:50:50 INFO - 'tpaint', 11:50:50 INFO - 'sessionrestore', 11:50:50 INFO - 'sessionrestore_no_auto_restore', 11:50:50 INFO - 'tabpaint']}, 11:50:50 INFO - 'other-e10s': {'tests': ['a11yr', 11:50:50 INFO - 'ts_paint', 11:50:50 INFO - 'tpaint', 11:50:50 INFO - 'sessionrestore', 11:50:50 INFO - 'sessionrestore_no_auto_restore', 11:50:50 INFO - 'tabpaint']}, 11:50:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:50:50 INFO - 'ts_paint', 11:50:50 INFO - 'tpaint', 11:50:50 INFO - 'sessionrestore', 11:50:50 INFO - 'sessionrestore_no_auto_restore', 11:50:50 INFO - 'tabpaint']}, 11:50:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:50:50 INFO - 'ts_paint', 11:50:50 INFO - 'tpaint', 11:50:50 INFO - 'sessionrestore', 11:50:50 INFO - 'sessionrestore_no_auto_restore', 11:50:50 INFO - 'tabpaint']}, 11:50:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['a11yr', 11:50:50 INFO - 'ts_paint', 11:50:50 INFO - 'tpaint', 11:50:50 INFO - 'sessionrestore', 11:50:50 INFO - 'sessionrestore_no_auto_restore', 11:50:50 INFO - 'tabpaint']}, 11:50:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['a11yr', 11:50:50 INFO - 'ts_paint', 11:50:50 INFO - 'tpaint', 11:50:50 INFO - 'sessionrestore', 11:50:50 INFO - 'sessionrestore_no_auto_restore', 11:50:50 INFO - 'tabpaint']}, 11:50:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['tsvgx', 11:50:50 INFO - 'tsvgr_opacity', 11:50:50 INFO - 'tart', 11:50:50 INFO - 'tscrollx', 11:50:50 INFO - 'cart']}, 11:50:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:50:50 INFO - 'tsvgr_opacity', 11:50:50 INFO - 'tart', 11:50:50 INFO - 'tscrollx', 11:50:50 INFO - 'cart']}, 11:50:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:50 INFO - 'talos_options': ['--disable-e10s'], 11:50:50 INFO - 'tests': ['tp5o']}, 11:50:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:50 INFO - 'tests': ['tp5o']}, 11:50:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:50 INFO - 'talos_options': ['--disable-e10s', 11:50:50 INFO - '--xperf_path', 11:50:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:50:50 INFO - 'tests': ['tp5n']}, 11:50:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:50 INFO - 'talos_options': ['--xperf_path', 11:50:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:50:50 INFO - 'tests': ['tp5n']}}, 11:50:50 INFO - 'talos.zip': {'path': '', 11:50:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:50:50 INFO - Running post-action listener: _resource_record_post_action 11:50:50 INFO - ##### 11:50:50 INFO - ##### Finished populate-webroot step (success) 11:50:50 INFO - ##### 11:50:50 INFO - ##### 11:50:50 INFO - ##### Running create-virtualenv step. 11:50:50 INFO - ##### 11:50:50 INFO - Running pre-action listener: _resource_record_pre_action 11:50:50 INFO - Running main action method: create_virtualenv 11:50:50 INFO - Creating virtualenv /builds/slave/test/build/venv 11:50:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:50:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:50:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:50:51 INFO - Using real prefix '/tools/python27' 11:50:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:50:51 INFO - Installing distribute.............................................................................................................................................................................................done. 11:50:54 INFO - Installing pip.................done. 11:50:54 INFO - Return code: 0 11:50:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101761a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101767570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc3d7a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, '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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:50:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:50:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:50:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:54 INFO - 'HOME': '/Users/cltbld', 11:50:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:54 INFO - 'LOGNAME': 'cltbld', 11:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:54 INFO - 'MOZ_NO_REMOTE': '1', 11:50:54 INFO - 'NO_EM_RESTART': '1', 11:50:54 INFO - 'PAGER': '/bin/cat', 11:50:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:54 INFO - 'PWD': '/builds/slave/test', 11:50:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:54 INFO - 'SHELL': '/bin/bash', 11:50:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:50:54 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:50:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:54 INFO - 'USER': 'cltbld', 11:50:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:54 INFO - 'XPC_FLAGS': '0x0', 11:50:54 INFO - 'XPC_SERVICE_NAME': '0', 11:50:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:50:54 INFO - Cleaning up... 11:50:54 INFO - Return code: 0 11:50:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101761a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101767570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc3d7a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, '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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:50:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:50:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:50:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:54 INFO - 'HOME': '/Users/cltbld', 11:50:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:54 INFO - 'LOGNAME': 'cltbld', 11:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:54 INFO - 'MOZ_NO_REMOTE': '1', 11:50:54 INFO - 'NO_EM_RESTART': '1', 11:50:54 INFO - 'PAGER': '/bin/cat', 11:50:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:54 INFO - 'PWD': '/builds/slave/test', 11:50:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:54 INFO - 'SHELL': '/bin/bash', 11:50:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:50:54 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:50:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:54 INFO - 'USER': 'cltbld', 11:50:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:54 INFO - 'XPC_FLAGS': '0x0', 11:50:54 INFO - 'XPC_SERVICE_NAME': '0', 11:50:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:55 INFO - Downloading/unpacking psutil>=3.1.1 11:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:50:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:50:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:50:58 INFO - Installing collected packages: psutil 11:50:58 INFO - Running setup.py install for psutil 11:50:58 INFO - building 'psutil._psutil_osx' extension 11:50:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:50:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:50:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:50:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:50:58 INFO - building 'psutil._psutil_posix' extension 11:50:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:50:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:50:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:50:59 INFO - ^ 11:50:59 INFO - 1 warning generated. 11:50:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:50:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:50:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:50:59 INFO - Successfully installed psutil 11:50:59 INFO - Cleaning up... 11:50:59 INFO - Return code: 0 11:50:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:59 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 0x101761a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101767570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc3d7a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, '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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:50:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:50:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:59 INFO - 'HOME': '/Users/cltbld', 11:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:59 INFO - 'LOGNAME': 'cltbld', 11:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:59 INFO - 'MOZ_NO_REMOTE': '1', 11:50:59 INFO - 'NO_EM_RESTART': '1', 11:50:59 INFO - 'PAGER': '/bin/cat', 11:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:59 INFO - 'PWD': '/builds/slave/test', 11:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:59 INFO - 'SHELL': '/bin/bash', 11:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:50:59 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:59 INFO - 'USER': 'cltbld', 11:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:59 INFO - 'XPC_FLAGS': '0x0', 11:50:59 INFO - 'XPC_SERVICE_NAME': '0', 11:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:51:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:51:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:51:02 INFO - Installing collected packages: mozsystemmonitor 11:51:02 INFO - Running setup.py install for mozsystemmonitor 11:51:02 INFO - Successfully installed mozsystemmonitor 11:51:02 INFO - Cleaning up... 11:51:02 INFO - Return code: 0 11:51:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:02 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 0x101761a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101767570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc3d7a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, '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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:51:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:51:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:51:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:51:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:02 INFO - 'HOME': '/Users/cltbld', 11:51:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:02 INFO - 'LOGNAME': 'cltbld', 11:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:02 INFO - 'MOZ_NO_REMOTE': '1', 11:51:02 INFO - 'NO_EM_RESTART': '1', 11:51:02 INFO - 'PAGER': '/bin/cat', 11:51:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:02 INFO - 'PWD': '/builds/slave/test', 11:51:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:02 INFO - 'SHELL': '/bin/bash', 11:51:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:51:02 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:51:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:02 INFO - 'USER': 'cltbld', 11:51:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:02 INFO - 'XPC_FLAGS': '0x0', 11:51:02 INFO - 'XPC_SERVICE_NAME': '0', 11:51:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:02 INFO - Downloading/unpacking blobuploader==1.2.4 11:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:04 INFO - Downloading blobuploader-1.2.4.tar.gz 11:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:51:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:51:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:06 INFO - Downloading docopt-0.6.1.tar.gz 11:51:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:51:06 INFO - Installing collected packages: blobuploader, requests, docopt 11:51:06 INFO - Running setup.py install for blobuploader 11:51:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:51:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:51:06 INFO - Running setup.py install for requests 11:51:06 INFO - Running setup.py install for docopt 11:51:06 INFO - Successfully installed blobuploader requests docopt 11:51:06 INFO - Cleaning up... 11:51:07 INFO - Return code: 0 11:51:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:07 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 0x101761a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101767570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc3d7a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, '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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:51:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:51:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:51:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:07 INFO - 'HOME': '/Users/cltbld', 11:51:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:07 INFO - 'LOGNAME': 'cltbld', 11:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:07 INFO - 'MOZ_NO_REMOTE': '1', 11:51:07 INFO - 'NO_EM_RESTART': '1', 11:51:07 INFO - 'PAGER': '/bin/cat', 11:51:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:07 INFO - 'PWD': '/builds/slave/test', 11:51:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:07 INFO - 'SHELL': '/bin/bash', 11:51:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:51:07 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:51:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:07 INFO - 'USER': 'cltbld', 11:51:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:07 INFO - 'XPC_FLAGS': '0x0', 11:51:07 INFO - 'XPC_SERVICE_NAME': '0', 11:51:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:51:07 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-qEoM2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:51:07 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-P7LNei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:51:07 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-55PYPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:51:07 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-_0Z80U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:51:07 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-BXGX5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:51:07 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-YL3B5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:51:07 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-2YBmz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-6plxky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-MJZXQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-NPp7pN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-BcUiEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-tksmRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-1wyU3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ZwLMLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-4PgFR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-p1ayxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:51:08 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ywV_Lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:51:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:51:09 INFO - Running setup.py install for manifestparser 11:51:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:51:09 INFO - Running setup.py install for mozcrash 11:51:09 INFO - Running setup.py install for mozdebug 11:51:09 INFO - Running setup.py install for mozdevice 11:51:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:51:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:51:09 INFO - Running setup.py install for mozfile 11:51:09 INFO - Running setup.py install for mozhttpd 11:51:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:51:09 INFO - Running setup.py install for mozinfo 11:51:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:51:10 INFO - Running setup.py install for mozInstall 11:51:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:51:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:51:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:51:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:51:10 INFO - Running setup.py install for mozleak 11:51:10 INFO - Running setup.py install for mozlog 11:51:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:51:10 INFO - Running setup.py install for moznetwork 11:51:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:51:10 INFO - Running setup.py install for mozprocess 11:51:10 INFO - Running setup.py install for mozprofile 11:51:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:51:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:51:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:51:11 INFO - Running setup.py install for mozrunner 11:51:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:51:11 INFO - Running setup.py install for mozscreenshot 11:51:11 INFO - Running setup.py install for moztest 11:51:11 INFO - Running setup.py install for mozversion 11:51:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:51:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:51:11 INFO - Cleaning up... 11:51:11 INFO - Return code: 0 11:51:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:11 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 0x101761a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101767570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc3d7a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, '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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:51:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:51:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:51:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:51:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:11 INFO - 'HOME': '/Users/cltbld', 11:51:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:11 INFO - 'LOGNAME': 'cltbld', 11:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:11 INFO - 'MOZ_NO_REMOTE': '1', 11:51:11 INFO - 'NO_EM_RESTART': '1', 11:51:11 INFO - 'PAGER': '/bin/cat', 11:51:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:11 INFO - 'PWD': '/builds/slave/test', 11:51:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:11 INFO - 'SHELL': '/bin/bash', 11:51:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:51:11 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:51:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:11 INFO - 'USER': 'cltbld', 11:51:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:11 INFO - 'XPC_FLAGS': '0x0', 11:51:11 INFO - 'XPC_SERVICE_NAME': '0', 11:51:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:51:11 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-BkvYXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-6Vf0qF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-vRlImF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-U9FCRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-TmXITb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ONhYVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-qibfY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-k_bp62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-a0Tg3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-_qJi0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:51:12 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-EWCJT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:51:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-qtV7lJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:51:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-bHtP2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:51:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-MnnIGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-rLDVek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:51:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-40UXUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:51:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-4R5nj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:51:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:16 INFO - Downloading blessings-1.6.tar.gz 11:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:51:16 INFO - Installing collected packages: blessings 11:51:16 INFO - Running setup.py install for blessings 11:51:16 INFO - Successfully installed blessings 11:51:16 INFO - Cleaning up... 11:51:16 INFO - Return code: 0 11:51:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:51:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:51:16 INFO - Reading from file tmpfile_stdout 11:51:16 INFO - Current package versions: 11:51:16 INFO - blessings == 1.6 11:51:16 INFO - blobuploader == 1.2.4 11:51:16 INFO - docopt == 0.6.1 11:51:16 INFO - manifestparser == 1.1 11:51:16 INFO - mozInstall == 1.12 11:51:16 INFO - mozcrash == 0.17 11:51:16 INFO - mozdebug == 0.1 11:51:16 INFO - mozdevice == 0.48 11:51:16 INFO - mozfile == 1.2 11:51:16 INFO - mozhttpd == 0.7 11:51:16 INFO - mozinfo == 0.9 11:51:16 INFO - mozleak == 0.1 11:51:16 INFO - mozlog == 3.2 11:51:16 INFO - moznetwork == 0.27 11:51:16 INFO - mozprocess == 0.22 11:51:16 INFO - mozprofile == 0.28 11:51:16 INFO - mozrunner == 6.11 11:51:16 INFO - mozscreenshot == 0.1 11:51:16 INFO - mozsystemmonitor == 0.3 11:51:16 INFO - moztest == 0.7 11:51:16 INFO - mozversion == 1.4 11:51:16 INFO - psutil == 3.1.1 11:51:16 INFO - requests == 1.2.3 11:51:16 INFO - wsgiref == 0.1.2 11:51:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:16 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 0x101761a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101767570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc3d7a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, '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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:51:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:51:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:51:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:16 INFO - 'HOME': '/Users/cltbld', 11:51:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:16 INFO - 'LOGNAME': 'cltbld', 11:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:16 INFO - 'MOZ_NO_REMOTE': '1', 11:51:16 INFO - 'NO_EM_RESTART': '1', 11:51:16 INFO - 'PAGER': '/bin/cat', 11:51:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:16 INFO - 'PWD': '/builds/slave/test', 11:51:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:16 INFO - 'SHELL': '/bin/bash', 11:51:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:51:16 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:51:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:16 INFO - 'USER': 'cltbld', 11:51:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:16 INFO - 'XPC_FLAGS': '0x0', 11:51:16 INFO - 'XPC_SERVICE_NAME': '0', 11:51:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:51:17 INFO - Cleaning up... 11:51:17 INFO - Return code: 0 11:51:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:17 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 0x101761a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101767570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc3d7a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, '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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:51:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:17 INFO - 'HOME': '/Users/cltbld', 11:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:17 INFO - 'LOGNAME': 'cltbld', 11:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:17 INFO - 'MOZ_NO_REMOTE': '1', 11:51:17 INFO - 'NO_EM_RESTART': '1', 11:51:17 INFO - 'PAGER': '/bin/cat', 11:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:17 INFO - 'PWD': '/builds/slave/test', 11:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:17 INFO - 'SHELL': '/bin/bash', 11:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:51:17 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:17 INFO - 'USER': 'cltbld', 11:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:17 INFO - 'XPC_FLAGS': '0x0', 11:51:17 INFO - 'XPC_SERVICE_NAME': '0', 11:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:17 INFO - Downloading/unpacking jsonschema 11:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:51:19 INFO - Downloading/unpacking functools32 (from jsonschema) 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51:20 INFO - Downloading functools32-3.2.3-2.tar.gz 11:51:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:51:20 INFO - warning: no files found matching '*.txt' 11:51:20 INFO - no previously-included directories found matching 'build' 11:51:20 INFO - no previously-included directories found matching 'dist' 11:51:20 INFO - no previously-included directories found matching '.git*' 11:51:20 INFO - Installing collected packages: jsonschema, functools32 11:51:20 INFO - Running setup.py install for functools32 11:51:20 INFO - warning: no files found matching '*.txt' 11:51:20 INFO - no previously-included directories found matching 'build' 11:51:20 INFO - no previously-included directories found matching 'dist' 11:51:20 INFO - no previously-included directories found matching '.git*' 11:51:20 INFO - Successfully installed jsonschema functools32 11:51:20 INFO - Cleaning up... 11:51:20 INFO - Return code: 0 11:51:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:51:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:51:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:51:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101761a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101767570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc3d7a130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, '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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:51:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 11:51:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 11:51:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:51:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:21 INFO - 'HOME': '/Users/cltbld', 11:51:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:21 INFO - 'LOGNAME': 'cltbld', 11:51:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:21 INFO - 'MOZ_NO_REMOTE': '1', 11:51:21 INFO - 'NO_EM_RESTART': '1', 11:51:21 INFO - 'PAGER': '/bin/cat', 11:51:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:21 INFO - 'PWD': '/builds/slave/test', 11:51:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:21 INFO - 'SHELL': '/bin/bash', 11:51:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:51:21 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:51:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:21 INFO - 'USER': 'cltbld', 11:51:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:21 INFO - 'XPC_FLAGS': '0x0', 11:51:21 INFO - 'XPC_SERVICE_NAME': '0', 11:51:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:51:21 INFO - Downloading/unpacking flake8 11:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:51:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:51:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:51:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51: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 11:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:51:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:51:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:51:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:51:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:51:25 INFO - no previously-included directories found matching 'docs/_build' 11:51:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:51:25 INFO - Running setup.py install for pep8 11:51:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:51:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:51:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:51:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:51:25 INFO - no previously-included directories found matching 'docs/_build' 11:51:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:51:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:51:25 INFO - Cleaning up... 11:51:25 INFO - Return code: 0 11:51:25 INFO - Running post-action listener: _resource_record_post_action 11:51:25 INFO - Running post-action listener: _start_resource_monitoring 11:51:25 INFO - Starting resource monitoring. 11:51:25 INFO - ##### 11:51:25 INFO - ##### Finished create-virtualenv step (success) 11:51:25 INFO - ##### 11:51:25 INFO - ##### 11:51:25 INFO - ##### Running install step. 11:51:25 INFO - ##### 11:51:25 INFO - Running pre-action listener: _resource_record_pre_action 11:51:25 INFO - Running main action method: install 11:51:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:51:25 INFO - Reading from file tmpfile_stdout 11:51:25 INFO - Detecting whether we're running mozinstall >=1.0... 11:51:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:51:25 INFO - Reading from file tmpfile_stdout 11:51:25 INFO - Output received: 11:51:25 INFO - Usage: mozinstall [options] installer 11:51:25 INFO - Options: 11:51:25 INFO - -h, --help show this help message and exit 11:51:25 INFO - -d DEST, --destination=DEST 11:51:25 INFO - Directory to install application into. [default: 11:51:25 INFO - "/builds/slave/test"] 11:51:25 INFO - --app=APP Application being installed. [default: firefox] 11:51:25 INFO - mkdir: /builds/slave/test/build/application 11:51:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:51:59 INFO - Reading from file tmpfile_stdout 11:51:59 INFO - Output received: 11:51:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:51:59 INFO - Running post-action listener: _resource_record_post_action 11:51:59 INFO - ##### 11:51:59 INFO - ##### Finished install step (success) 11:51:59 INFO - ##### 11:51:59 INFO - ##### 11:51:59 INFO - ##### Running run-tests step. 11:51:59 INFO - ##### 11:51:59 INFO - Running pre-action listener: _resource_record_pre_action 11:51:59 INFO - Running main action method: run_tests 11:51:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:51:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:51:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:51:59 INFO - 2016-06-01 11:51:59.450 screenresolution[1652:8324] starting screenresolution argv=screenresolution get 11:51:59 INFO - 2016-06-01 11:51:59.452 screenresolution[1652:8324] Display 0: 1600x1200x32@60 11:51:59 INFO - 2016-06-01 11:51:59.458 screenresolution[1653:8326] starting screenresolution argv=screenresolution list 11:51:59 INFO - Available Modes on Display 0 11:51:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:51:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:51:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:51:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:51:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:51:59 INFO - Intel Iris: 11:51:59 INFO - Chipset Model: Intel Iris 11:51:59 INFO - Type: GPU 11:51:59 INFO - Bus: Built-In 11:51:59 INFO - VRAM (Dynamic, Max): 1536 MB 11:51:59 INFO - Vendor: Intel (0x8086) 11:51:59 INFO - Device ID: 0x0a2e 11:51:59 INFO - Revision ID: 0x0009 11:51:59 INFO - Displays: 11:51:59 INFO - Display: 11:51:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:51:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:51:59 INFO - Display Serial Number: 1600x1200 60 11:51:59 INFO - Main Display: Yes 11:51:59 INFO - Mirror: Off 11:51:59 INFO - Online: Yes 11:51:59 INFO - Rotation: Supported 11:51:59 INFO - Return code: 0 11:51:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:51:59 INFO - Python 2.7.3 11:51:59 INFO - Return code: 0 11:51:59 INFO - grabbing minidump binary from tooltool 11:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:51:59 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 0x101605d30>, '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 0x101429db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1015fcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:51:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:51:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:51:59 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:51:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:51:59 INFO - Return code: 0 11:51:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:51:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:51:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:51:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:51:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:51:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:52:00 INFO - Return code: 0 11:52:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:52:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0306 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:52:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkJWAjUSUm/Render', 11:52:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:52:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:52:00 INFO - 'HOME': '/Users/cltbld', 11:52:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:52:00 INFO - 'LOGNAME': 'cltbld', 11:52:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:52:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:00 INFO - 'MOZ_NO_REMOTE': '1', 11:52:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:52:00 INFO - 'NO_EM_RESTART': '1', 11:52:00 INFO - 'PAGER': '/bin/cat', 11:52:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:00 INFO - 'PWD': '/builds/slave/test', 11:52:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:52:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:52:00 INFO - 'SHELL': '/bin/bash', 11:52:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rlw0uKJ90q/Listeners', 11:52:00 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 11:52:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:52:00 INFO - 'USER': 'cltbld', 11:52:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:52:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:00 INFO - 'XPC_FLAGS': '0x0', 11:52:00 INFO - 'XPC_SERVICE_NAME': '0', 11:52:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:52:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:52:01 INFO - mozversion application_buildid: 20160601104449 11:52:01 INFO - mozversion application_changeset: 359674b9970a98e42c97184e9d7c59c89bac06d7 11:52:01 INFO - mozversion application_display_name: Nightly 11:52:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:52:01 INFO - mozversion application_name: Firefox 11:52:01 INFO - mozversion application_remotingname: firefox 11:52:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:52:01 INFO - mozversion application_vendor: Mozilla 11:52:01 INFO - mozversion application_version: 49.0a1 11:52:01 INFO - mozversion platform_buildid: 20160601104449 11:52:01 INFO - mozversion platform_changeset: 359674b9970a98e42c97184e9d7c59c89bac06d7 11:52:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:52:01 INFO - mozversion platform_version: 49.0a1 11:52:01 INFO - using testdate: 1464807121 11:52:01 INFO - actual date: 1464807121 11:52:01 INFO - starting webserver on 'localhost:49275' 11:52:01 INFO - SUITE-START | Running 2 tests 11:52:01 INFO - TEST-START | dromaeo_css 11:52:01 INFO - operating with platform_type : mac_ 11:52:01 INFO - Initialising browser for dromaeo_css test... 11:52:01 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmp7tLnqf/profile http://localhost:49275/getInfo.html) 11:52:03 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 11:52:03 INFO - PROCESS | 1671 | colorDepth:24 11:52:03 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 11:52:03 INFO - PROCESS | 1671 | __metrics 11:52:03 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:52:03 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:52:04 INFO - TEST-INFO | 1671: exit 0 11:52:04 INFO - Browser initialized. 11:52:04 INFO - Running cycle 1/1 for dromaeo_css test... 11:52:04 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmp7tLnqf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:52:16 INFO - PROCESS | 1674 | RSS: Main: 242012160 11:52:16 INFO - PROCESS | 1674 | 11:53:07 INFO - PROCESS | 1674 | 1464807187825 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:55:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 11:55:19 INFO - PROCESS | 1674 | RSS: Main: 336052224 11:55:19 INFO - PROCESS | 1674 | 11:58:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 11:58:22 INFO - PROCESS | 1674 | RSS: Main: 376582144 11:58:22 INFO - PROCESS | 1674 | 12:01:24 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 12:01:25 INFO - PROCESS | 1674 | RSS: Main: 374714368 12:01:25 INFO - PROCESS | 1674 | 12:04:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 12:04:28 INFO - PROCESS | 1674 | RSS: Main: 372908032 12:04:28 INFO - PROCESS | 1674 | 12:07:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 12:07:32 INFO - PROCESS | 1674 | RSS: Main: 349159424 12:07:32 INFO - PROCESS | 1674 | 12:10:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:10:35 INFO - PROCESS | 1674 | RSS: Main: 384856064 12:10:35 INFO - PROCESS | 1674 | 12:10:35 INFO - PROCESS | 1674 | __start_tp_report 12:10:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 12:10:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 12:10:35 INFO - PROCESS | 1674 | |i|pagename|runs| 12:10:35 INFO - PROCESS | 1674 | |0;dojo.html;3482.320947415254;3482.6169121396642;3492.284081763238;3501.562304711911;3549.716022718183;5639.351474580424;5682.119271512917;5705.914411283824;5792.420757924211;5821;5677.687727175004;5720.2849643794525;5826.184334193216;5828.562857785672;5869.1196320551835;5092.439831618519;5111.08000559899;5185.351831021122;5201.583873290147;5294.364676238857;5280.735963201843;5308.973455132719;5311.044012077844;5335.573154147659;5352.57179425645;5105.566026887719;5114.718690472023;5155.994581056658;5185.766640501187;5211.296474975887;5134.948650513485;5161.200013997836;5178.249153872705;5221.164613661796;5238.842834714965;5159.71621560814;5166.354205724285;5173.456787037382;5227.869303267411;5250.501202385767;5558.249636299089;5614.578906581982;5618.353889302742;5680.31836179657;5688.687122208277;5258.7370631468275;6229.4393504584805;6266.154069200645;6276.435120839101;6289.685515724242;6256.562040657156;6324.956382196932;6337.809865704036;6344.587601805914;6352.745890164388;5645.435456454363;5751.827445176651;5754.741036653319;5807.041838096667;5816.47651711348;6246.812595622138;6292.056191571301;6299.464545513682;6304;6313.842153946188;6089.607648435413;6152.138700581922;6167.321594624587;6230.190075290183;6254;6244.500439964792;6311.715972781236;6318.62088274705;6318.905216421757;6320.494360451153;6112.877742445126;6259.2488901332135;6264.686765661699;6283.59156654816;6288.9056664150075;6132.386102851662;6149.708561202197;6233.625982441068;6248.812535623939;6289.371062893674;4944.233643785147;4967.081089998362;4974.602031837444;4993;5000.799968001312;4414.874207077175;4420.668449866209;4428.778561071998;4455.465344158722;4487.282034874404;5605.607607467438;5683.77264909399;5687.146927960839;5692.829215123553;5695.145728140812;6227.532935029801;6275.937240627627;6280.403361680725;6320.273168585672;6338;5705.600607957404;5719.256496655408;5722.484976352148;5733.226014488074;5770.798022069207;5274.657147285414;5324.387695414999;5371.248025276464;5384.300040994645;5391.16436952273;6258.812235632939;6328.114064030948;6372.235331760033;6378.425941665179;6379.712912918837;6282.591631543935;6287.779927702509;6295.087212354275;6302.810915672537;6322.304546499973;5085.618578606545;6248.187735594343;6264.624122552479;6363.872722545616;6379.52153588492;6211.3478084802255;6214.937850621436;6215.720292586754;6218.502519798496;6223.844403889758;6273.1844860167885;6284.277308109626;6298.937010629835;6298.937010629835;6321.051842223703;5812.9709351452975;6091.969540152271;6104.48947159306;6106.3283038866575;6139.584134579286;6092.603980741238;6152.692365381802;6158.04550294705;6164.137020817179;6168.784092556739;5998.550108741949;6003.789867354796;6036.8188954331445;6048.395160484092;6071.726772295169;5952.107183922446;6096.878062438637;6139.171211886338;6160.7535698571555;6188.504919606511;6029.3669164737075;6037.305709843425;6044.395560444096;6048.274207095177;6076.544259180668 12:10:35 INFO - PROCESS | 1674 | |1;ext.html;501.1005245584176;509.17259453388266;510.06657816196355;530.694850460985;534.927784749059;30715.539266911015;31624;32568.67431325686;41689;42635.36046959298;44711.77644111775;45390.773046134804;45402.7729861351;45460.54539454605;45598;9517.571709273083;9762.26782991275;9766;9775.804483910317;9776.120149186572;9449.76375590609;9614.990426005274;9627.518624068804;9629.903700963006;9632.325737198398;9575.186109180693;9613;9621.422714637145;9622.134007939285;9632.277579181555;9538.761530961712;9604.51977401127;9605.615775369046;9611.951940240324;9659.5170241488;9518.095780900803;9623.133917947349;9629.181519570844;9632.422054676741;9669.226461883103;41658.79170604158;41973;42098.15803683909;42116.1576768466;42130.78934605338;3620.5655321361605;3638.945415818738;3802.714796390254;3839.9616003840165;4435.866923992286;31434.68565314363;32210.71157153711;32434.513482297785;32714.6728532714;32798.3440331192;3262.902112936592;3360.092774950774;3367.478040903664;3405.540252065989;3433.4678124890684;503450;503906;504535;506773;508975.45512272575;62455.68772156201;63310.73378532404;63428.048579271344;63500;63529.682351587944;66163.33836661668;66789.9981500278;66817.66591167013;66835.66582167058;66998;13986.86013139876;16670.499885003468;16880.409185678443;16923.238454269587;16977.745333820003;13048.673783155307;13116.868831311565;13129.146605470805;13129.474821007243;13139.474421023053;16736.6610671146;17046.403375881786;17166.742498862528;17167.65664686699;17222;6494.902576461357;6540.4767618590395;6542.149520562297;6554.639494827735;6601.240857301375;916.7204002779083;953.7043516509904;1047.6490375724065;1094.8795632480421;1101.4107452512867;24917.380370275874;29493;29561.704382956326;30309.93915212957;30673.38653226923;985.8077674853334;1134.3364131982883;1156.2195518025298;1206.6983254186453;1222.6123349997638;12589.55936542235;15809.604759880865;15837.445689400816;16283.511494655178;16284.592885177964;563458;675248.6237568879;675327;678114;684379;61663.766724665256;61828;61833.763324733256;61860.07209891855;62143.37856621466;1270.8919741822049;1339.0961101256612;1398.867107624774;1433.4911106557022;1445.4218312675014;27819.16542503647;31529.52705709462;31546.211344716565;31799.364012720078;32155;51166.48833511617;51351;52085.7395713019;52222;52237.47762522478;487593;493141;494010;495532;498364;35260.64739352574;35701.64298356983;41633.79183104065;43114.56885431105;43217.35173972456;49050;49857.50142498528;50501.21240906873;50590.7352316195;50736;37433.81283093567;38492;40296.798516007235;40928.79535602303;41235.587644123174;39541;39543;39941;40266.597334026286;40750.59249407468;38375;38861;39132.60867391404;39405.802970984965;39444.61943831952;33126.668733312355;34646.30707385889;35511.46732798959;35616.46575301424;35647.10882227965;22832.31503054911;24809.00763969421;25463.98144074216;25476;25736.144914814937 12:10:35 INFO - PROCESS | 1674 | |2;jquery.html;386.34514497926017;386.65201318813064;391.72018850291283;395.50759304665695;416.6958120571982;19788.90105549472;19994.50013749657;20910.89544552275;20998.370048898538;21000.36998890032;17307.82692173078;19956.60086798263;20731.585368292624;21367.893160534215;21826;5235.1885457753915;5766.826995190151;5943.613665111776;5973.163757074013;5975.581709280352;5848.649081055129;5863.677497737623;5896.233489646362;5953.464188223059;5960.374160713128;5833.649981001132;5890.440408161217;5908.763649454017;5910.763569457216;5938.910916336258;5690.97562438765;5863.472287494124;5929.911051334234;5941.257342832146;6061.787837425669;5861.70691465425;5873.765049398019;5879.0593505039415;5895.793647222326;5898.056310990264;13269.203847769164;13342.466301347935;23021.96401161952;24476.877615611986;25703.97184112633;2157.4822042709793;2158.2554018863466;2295.3343530376337;2764.156932135698;2816.690164081949;9963.65127220551;10019;10462.738431539196;12795.93602031984;15010.024348417319;1896.421591414618;1926.3931861463593;2021.878687278768;2064.1743302678897;2093.204582258747;100762;113400;114765;117647;120237;11344.886551134548;11495.137864660184;11588.13089018327;11647.941760291144;11750.529978800923;14580.489682861049;14666.633334166518;14690;14714.117152970855;14728.779068313985;8500.74497765068;8689.391742578086;8690.08754080825;8791.428557143892;8825.955870220609;8566.27186689126;8580.65677372898;8605.13948605147;8697.739067827975;8729.345299102593;4462.6388951370145;4555.40779698637;4580.335851301543;4616.492185859553;4635.142498637762;1869.9158537865828;1911.3405874973112;1919.500929758245;1925.9422217333502;1964.0965156028387;865.523961820996;914.8764916736288;916.3951791817368;937.5921474158763;954.370115723619;17323.740143897852;19305.420837374902;20904.790952090283;22054;23138.884305578365;965.7826988927576;975.7658162041201;995.5967833027636;1037.113268155716;1040.6409788622914;11174.26881955911;11566.710832229262;11684.298942063333;11718.414079296;11727.530898764124;501758;502954;504533.4773326183;596150;602550;98132.50933745428;102030;139541.72062257223;139933;141920.29039854734;988.4316518002033;996.6013594562233;1030.1295405382589;1031.4533297352416;1032.3341444768232;15555.84444155544;18948.43154705361;19200.903995479934;19274.8072519273;19366;42781.09549538732;53676;53803.46196538141;54216.72891635517;55175.724121379135;74942;76560.85158722736;76804;78095;79133.20866791258;7434.3309102181065;7962.203779622223;8038.83922321562;8061.072976607765;8380.790480238044;9162.541872906462;12399.512058553031;12549.372531373578;13160.341982900625;13173.077884547989;10350.585976561155;11200.43997800123;11601.883981160418;11644.475998579916;11841.5855445059;10776.461176941279;11074.501647425725;11190.160737944523;11504.101823199078;11917;6625.536212465081;7923.643436045507;11444.885551144382;11536.653900383002;12019.879801201876;7871.960640196762;8192.631331590212;8236.50580965144;8319.584020798815;8418.621162047844;7139.82150446243;11024.228304018641;11722.648320550397;11746.17776755619;11910.344931028876 12:10:35 INFO - PROCESS | 1674 | |3;mootools.html;1360.1023324605762;1560.0561660195585;1585.254930182814;1829.1768704083158;1971.8126777956097;7929.5638739869355;8720.956395218023;8922.509261990588;8928.642854285821;9049.321300902426;8651.04838467768;8870.467771933689;8872.822543549126;8876;8887;1730.974035389467;2092.403664955488;2303.527776805751;2306.746257911628;2388.0925248405583;2398.412388964706;2428.7449817769143;2429.866357350345;2430.3195105370523;2433.4159801647565;2363.2083252110565;2382.2376839411395;2402.495475950044;2403.3631087761755;2409.8674572898485;2067.390119914629;2139.4116617930194;2141.764405915348;2156.54712510373;2351.5884720173985;2333.2766842278947;2408.9518209635708;2410.2166795791436;2416.0818888822137;2417.407735104893;8894.911050889537;8971.372003959725;8973.057828927998;8979.326550508738;8997.550122493849;4504.6396288296855;5269.947300527022;5434.972825135849;5436.238926550286;5440.755166017539;3578.8031658258788;3757.0231739747587;3757.0607348162957;3771.7925514096714;3772.1135533149686;1070.6948519671935;1095.2497539185683;1103.2001798695962;1105.4638500327335;1125.7073160978123;12076.396180191043;12082.214656047328;12087.03303735699;12095.455704493319;12127.151099423045;4738.620910327166;4778.498257682893;4879.878003049952;4880.267959805987;4889.975550122226;9948.751281218027;10110.545025473872;10115.797684046278;10122.190224782;10151.187904967584;4493.460784705856;5335.706536140549;5436.157395603688;5437.537809286176;5443.673379597237;4407.911841763147;4582.106489234568;4824.638152138604;4938.530839570235;4946.455889852114;3959.92080158401;4011;4069.4709687740997;4128.979355103205;4131.091159944807;1194.7013246688327;1298.0524217321408;1299.5451591942744;1307.6338625184962;1311.4688551136614;897.9910200897907;915.7756349694234;918.8621706744042;930.7853251506849;951.048951048951;5884.9705751470965;5896.850114227685;5932.2584676915385;6017.3681763414215;6153.261608606996;424.331677607769;438.63812354807413;443.18675230951567;454.3593533118353;455.731118640004;3921.509811273591;3970.741901776433;4043.959560404417;4049.8785036448953;4051.5745846686264;10513.211509136692;10817.513211905483;10853;10928.617498387519;10956.506957186786;10888.965548272901;10892.237543371888;10894.564217431373;10905.83641245382;10909.83635245472;7760.961195194101;8310.085890552155;8310.667573297122;8416.537090460084;8419.83160336802;5836.124581312837;6102.786402475892;6119.724612392364;6170.074488826712;6194.783182588588;9848.556814943488;10178.796424071625;10382.480875956324;10388;10392.740181495523;10267.486625668538;10327.173826094046;10457.372557646566;10472.68581942543;10476.79046419052;7013.018177455262;7293.927060729325;7710.730124445618;7730.652120654697;7866.056073271244;7124.216336203117;8581.01318348398;8672.739817805223;8684.609192586222;8698.739037828875;7534.4725869190825;7614.010178676736;7690.538567685957;7767.300942915164;7814.101378341564;7415.703371865065;7471.589062601609;7479.326860582573;7619;7628.160902300853;6245.18812554365;7144.24985376528;7192.820179495555;7202.559488102505;7206.783796486114;4632.2819962905805;4842.5157484251295;4916.508349165055;4984.651074424901;5064.18972964324;5017.096922553829;5054.444011158843;5073.492650735045;5119.232115182753;5198.376194856642 12:10:35 INFO - PROCESS | 1674 | |4;prototype.html;307.6892338737875;316.1858215096128;319.05718601532476;322.55648483335415;326.1617642658369;4650.3024546318065;5265.473452654733;5286.312779338686;5304;5325.733713314334;4124.340105583107;4789.563131060677;4877.951220487794;4948.802047918079;5235.55497782689;3317.871923545994;3473.687368136867;3990.221906728189;4069.2065047315787;4079.551249362568;3528.8412021459094;3953.655757042605;4008;4067.776272305022;4082.3060079786505;3323.60116785986;3501.859925602973;3627.6372362763645;3781.3193625147464;4014.899627509321;3290.8025518468967;3743.419769935664;3845.653891149782;3911.8630847920326;3927.7643341399607;3195.265089029513;3289.227031647561;3490.912727181815;3497.475378693191;3501.7918818007747;4276.294411422112;4532.229520981441;4807.711537307773;5129.948700512984;5201.349831271091;4224.471941007374;4533.546645335473;4595.954040459586;4638.165130276549;4664.253719404879;5040.84877453677;5534.723263836832;5535.750891209906;5555.722213889329;5591.133374326945;3218.6459489456142;3532.6820586147114;3808.333541630204;3817.2174704185577;3831.9042023949346;4236.85171019016;5414.999999999961;5738.4835364816945;5870.385643947863;5891.381404952461;3232.62402134431;3396.3377141457177;3790.962090379061;3832.5400951885395;3848.461215429842;3873.2447172801035;3884.592117827644;3884.6503814656817;3895.162540053902;3897.7271590988926;4535.659825513099;4544.522825103382;4545.818167273272;4557.726536407826;4561.885952851139;5008.799648014038;5309.893802124013;5343.625946183807;5385.727181693088;5601.831945041656;1073.7100982734619;1303.4134639412114;1317.5059352742721;1327.9070465067352;1328.4287756264898;729.9488736219827;778.9298963093349;787.9244830805909;808.720991258015;819.8685813577225;4074.3684728867074;4111.568285330057;4155.8337666493;4663.766811659403;5224.205050298072;5014.974925125351;5509.807156749494;5605.467480589337;5693.437952921202;5742.856428589235;4189.790510474464;5131.281620573122;5173.1464308388695;5215.24378965048;5228.503292187236;2484.6582845263356;2963.8215911305174;2974.917557709612;3017.7325523279837;3026.697330267;4839.080574690797;4968.677035992649;5918.644881307136;6018.488428483541;6036.547258955596;4744.976275118672;4918.2786024891;5512.4211957743855;5613.726440791486;5837;4965.999999999928;5141.945901090267;5155.484451554815;5358.713908661815;5822.7670893163795;5203.375594928632;5452.700101494456;5688.488036076773;5691.345495267931;5720;4476.597106260452;4782.545658162469;5334.013207556614;5608.298962629508;5711.942880571307;4638.443386793607;5961.880762384815;5982.4316689914385;5982.611130276518;6011.27864656244;3358.7480938930166;3367.1582104473882;3635.2190574211277;3792.108854419154;3794.5636251830288;5194.428612852659;5315.4418786028455;5619.943800562106;5714.514266287412;5722.396309679003;4409.867703968886;5069.138246498031;5550.333959924835;5607.943920560742;5615.747291371817;4580.381648477412;5286;5478.45215478449;5569.498745112959;5598.552115830806;5041.554845899663;5275.472452754694;5501.257330260363;5516.972415137899;5551.722413879371;5260.368755749335;5331.946680533145;5489.670619762827;5644.52021578154;5662.66024038559;4924.803007879645;5496.615236933376;5505.779768809202;5546.140348246028;5551.806361632269 12:10:35 INFO - PROCESS | 1674 | |5;yui.html;705.3299365602678;781.2968328504344;828.5567221536479;846.7502086884368;857.3526987124724;15086.999999999985;15440.150791706452;15468.38126474941;15469.381224750996;15473.30370133344;14046.789298160536;14050.578482645511;14086.366113524891;14088.36602352894;14109.294535273246;708.9822754431154;740.0194741966916;887.6626881784945;901.6934242357597;919.8022425178585;899.3524662243175;912.8585069314267;917.1058218237232;918.1277786103228;918.4672889723978;972.7568107973007;973.9172170365528;979.4319294808994;979.7746518300795;980.2403137568406;751.868423025971;780.9297163255334;782.3936449251876;783.0759703549809;976.4287891583432;679.4224908827506;706.4595584377955;734.8787450070732;735.2280105888796;735.6138027535533;14755.631109222355;14762.852371476256;14799.964002519828;14805.333759980827;14813.851861481355;13413;13611.251381174032;13624.318784060757;13630.659233519242;13879.861201387957;12094.048200699985;12260.080493962989;12459.813102803466;12612.81080783789;12822.230666159967;4561.954380456219;4574.245249533823;4585.403897493305;4736.171170045228;4754.191787396151;74474;75146.8727969081;75328.62335688286;76442.85335719968;76595.46809063843;9905.455199964072;10181.440020798778;10891.509882055327;10922.617708380327;10923.235373523936;21318.57362852734;21428.57142857134;21461.248856289956;21468.570628587655;21469.14123435076;7770.689172433153;7828.858441111428;7965.800854978672;8165.959170204111;8167.63245653947;4325.783710814447;4404.66964977628;4442.777861106932;4495.303227999665;4575.816967321336;3420.2133509293003;3516.191276006533;3516.7362447816004;3522.454019626962;3525.629808870083;653.150903825035;662.3906006474056;665.1785045468843;677.9017042528905;678.1015154919751;559.5383808358121;659.120074700271;665.8934570468701;671.1475733913235;686.080651926421;8431.367647426345;8500.192481714228;9060.592273347715;9112.8633070504;9139.49732764691;556.6381851796365;630.5964182922933;632.7975047984693;667.7829705345782;672.7409947170277;4402.603765661106;4417.277261867874;4621.445426548836;4638.397008388888;4717.481077081518;78426.6078669603;78557;79073.60463197762;80282;81451.48388128936;76480.47039059187;77410.61294693491;77568;77949.0512737186;79657;50125.74937125291;71192.64403678052;71553.64223178955;71612;71905.92141117888;7172.39034682037;9005.909940900769;9034.728958131267;9129.863052054357;9132.817343652956;45221.869453263935;45892.77053614711;46273.53726462827;46392;46655;64300.678496607215;64715.35284647093;65079.67460162669;65235;65238.673806630664;10012.64955726546;11657.475413606238;12024.33866137371;12269.325187114795;12269.877301226874;12696.555620553236;12992.415341309852;13344.466221351036;13368.799468007794;13418.060735748404;12051.33717645538;12122.151449398458;12240.265584064984;12241.877581224073;12414.068944828994;11874.228175168586;12015.639530814089;12108.21296615717;12289.938550307192;12453.626391208278;11883;11910.821337679768;11911.40442977865;12107.939460302641;12371.938140309241;10286.588536458758;10387.428691421743;10400.219983501116;10524.421156836448;10641.308314959502;9528.052511212029;9743.951280243553;10404.739881503023;10483.423411712429;10556.947215263874 12:10:35 INFO - PROCESS | 1674 | __end_tp_report 12:10:35 INFO - PROCESS | 1674 | __start_cc_report 12:10:35 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1263 12:10:35 INFO - PROCESS | 1674 | __end_cc_report 12:10:35 INFO - PROCESS | 1674 | __startTimestamp1464808235704__endTimestamp 12:10:35 INFO - PROCESS | 1674 | 12:10:35 INFO - PROCESS | 1674 | ------- Summary: start ------- 12:10:35 INFO - PROCESS | 1674 | Number of tests: 6 12:10:35 INFO - PROCESS | 1674 | 12:10:35 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5760.94 Median:6036.82 stddev:636.43 (10.5%) stddev-sans-first:612.77 12:10:35 INFO - PROCESS | 1674 | Values: 3482.3 3482.6 3492.3 3501.6 3549.7 5639.4 5682.1 5705.9 5792.4 5821.0 5677.7 5720.3 5826.2 5828.6 5869.1 5092.4 5111.1 5185.4 5201.6 5294.4 5280.7 5309.0 5311.0 5335.6 5352.6 5105.6 5114.7 5156.0 5185.8 5211.3 5134.9 5161.2 5178.2 5221.2 5238.8 5159.7 5166.4 5173.5 5227.9 5250.5 5558.2 5614.6 5618.4 5680.3 5688.7 5258.7 6229.4 6266.2 6276.4 6289.7 6256.6 6325.0 6337.8 6344.6 6352.7 5645.4 5751.8 5754.7 5807.0 5816.5 6246.8 6292.1 6299.5 6304.0 6313.8 6089.6 6152.1 6167.3 6230.2 6254.0 6244.5 6311.7 6318.6 6318.9 6320.5 6112.9 6259.2 6264.7 6283.6 6288.9 6132.4 6149.7 6233.6 6248.8 6289.4 4944.2 4967.1 4974.6 4993.0 5000.8 4414.9 4420.7 4428.8 4455.5 4487.3 5605.6 5683.8 5687.1 5692.8 5695.1 6227.5 6275.9 6280.4 6320.3 6338.0 5705.6 5719.3 5722.5 5733.2 5770.8 5274.7 5324.4 5371.2 5384.3 5391.2 6258.8 6328.1 6372.2 6378.4 6379.7 6282.6 6287.8 6295.1 6302.8 6322.3 5085.6 6248.2 6264.6 6363.9 6379.5 6211.3 6214.9 6215.7 6218.5 6223.8 6273.2 6284.3 6298.9 6298.9 6321.1 5813.0 6092.0 6104.5 6106.3 6139.6 6092.6 6152.7 6158.0 6164.1 6168.8 5998.6 6003.8 6036.8 6048.4 6071.7 5952.1 6096.9 6139.2 6160.8 6188.5 6029.4 6037.3 6044.4 6048.3 6076.5 12:10:35 INFO - PROCESS | 1674 | 12:10:35 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:69719.03 Median:42107.16 stddev:148643.95 (353.0%) stddev-sans-first:148970.09 12:10:35 INFO - PROCESS | 1674 | Values: 501.1 509.2 510.1 530.7 534.9 30715.5 31624.0 32568.7 41689.0 42635.4 44711.8 45390.8 45402.8 45460.5 45598.0 9517.6 9762.3 9766.0 9775.8 9776.1 9449.8 9615.0 9627.5 9629.9 9632.3 9575.2 9613.0 9621.4 9622.1 9632.3 9538.8 9604.5 9605.6 9612.0 9659.5 9518.1 9623.1 9629.2 9632.4 9669.2 41658.8 41973.0 42098.2 42116.2 42130.8 3620.6 3638.9 3802.7 3840.0 4435.9 31434.7 32210.7 32434.5 32714.7 32798.3 3262.9 3360.1 3367.5 3405.5 3433.5 503450.0 503906.0 504535.0 506773.0 508975.5 62455.7 63310.7 63428.0 63500.0 63529.7 66163.3 66790.0 66817.7 66835.7 66998.0 13986.9 16670.5 16880.4 16923.2 16977.7 13048.7 13116.9 13129.1 13129.5 13139.5 16736.7 17046.4 17166.7 17167.7 17222.0 6494.9 6540.5 6542.1 6554.6 6601.2 916.7 953.7 1047.6 1094.9 1101.4 24917.4 29493.0 29561.7 30309.9 30673.4 985.8 1134.3 1156.2 1206.7 1222.6 12589.6 15809.6 15837.4 16283.5 16284.6 563458.0 675248.6 675327.0 678114.0 684379.0 61663.8 61828.0 61833.8 61860.1 62143.4 1270.9 1339.1 1398.9 1433.5 1445.4 27819.2 31529.5 31546.2 31799.4 32155.0 51166.5 51351.0 52085.7 52222.0 52237.5 487593.0 493141.0 494010.0 495532.0 498364.0 35260.6 35701.6 41633.8 43114.6 43217.4 49050.0 49857.5 50501.2 50590.7 50736.0 37433.8 38492.0 40296.8 40928.8 41235.6 39541.0 39543.0 39941.0 40266.6 40750.6 38375.0 38861.0 39132.6 39405.8 39444.6 33126.7 34646.3 35511.5 35616.5 35647.1 22832.3 24809.0 25464.0 25476.0 25736.1 12:10:35 INFO - PROCESS | 1674 | 12:10:35 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:33055.13 Median:25090.42 stddev:91143.27 (363.3%) stddev-sans-first:91365.94 12:10:35 INFO - PROCESS | 1674 | Values: 386.3 386.7 391.7 395.5 416.7 19788.9 19994.5 20910.9 20998.4 21000.4 17307.8 19956.6 20731.6 21367.9 21826.0 5235.2 5766.8 5943.6 5973.2 5975.6 5848.6 5863.7 5896.2 5953.5 5960.4 5833.6 5890.4 5908.8 5910.8 5938.9 5691.0 5863.5 5929.9 5941.3 6061.8 5861.7 5873.8 5879.1 5895.8 5898.1 13269.2 13342.5 23022.0 24476.9 25704.0 2157.5 2158.3 2295.3 2764.2 2816.7 9963.7 10019.0 10462.7 12795.9 15010.0 1896.4 1926.4 2021.9 2064.2 2093.2 100762.0 113400.0 114765.0 117647.0 120237.0 11344.9 11495.1 11588.1 11647.9 11750.5 14580.5 14666.6 14690.0 14714.1 14728.8 8500.7 8689.4 8690.1 8791.4 8826.0 8566.3 8580.7 8605.1 8697.7 8729.3 4462.6 4555.4 4580.3 4616.5 4635.1 1869.9 1911.3 1919.5 1925.9 1964.1 865.5 914.9 916.4 937.6 954.4 17323.7 19305.4 20904.8 22054.0 23138.9 965.8 975.8 995.6 1037.1 1040.6 11174.3 11566.7 11684.3 11718.4 11727.5 501758.0 502954.0 504533.5 596150.0 602550.0 98132.5 102030.0 139541.7 139933.0 141920.3 988.4 996.6 1030.1 1031.5 1032.3 15555.8 18948.4 19200.9 19274.8 19366.0 42781.1 53676.0 53803.5 54216.7 55175.7 74942.0 76560.9 76804.0 78095.0 79133.2 7434.3 7962.2 8038.8 8061.1 8380.8 9162.5 12399.5 12549.4 13160.3 13173.1 10350.6 11200.4 11601.9 11644.5 11841.6 10776.5 11074.5 11190.2 11504.1 11917.0 6625.5 7923.6 11444.9 11536.7 12019.9 7872.0 8192.6 8236.5 8319.6 8418.6 7139.8 11024.2 11722.6 11746.2 11910.3 12:10:35 INFO - PROCESS | 1674 | 12:10:35 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5782.75 Median:2518.92 stddev:3301.87 (131.1%) stddev-sans-first:3294.40 12:10:35 INFO - PROCESS | 1674 | Values: 1360.1 1560.1 1585.3 1829.2 1971.8 7929.6 8721.0 8922.5 8928.6 9049.3 8651.0 8870.5 8872.8 8876.0 8887.0 1731.0 2092.4 2303.5 2306.7 2388.1 2398.4 2428.7 2429.9 2430.3 2433.4 2363.2 2382.2 2402.5 2403.4 2409.9 2067.4 2139.4 2141.8 2156.5 2351.6 2333.3 2409.0 2410.2 2416.1 2417.4 8894.9 8971.4 8973.1 8979.3 8997.6 4504.6 5269.9 5435.0 5436.2 5440.8 3578.8 3757.0 3757.1 3771.8 3772.1 1070.7 1095.2 1103.2 1105.5 1125.7 12076.4 12082.2 12087.0 12095.5 12127.2 4738.6 4778.5 4879.9 4880.3 4890.0 9948.8 10110.5 10115.8 10122.2 10151.2 4493.5 5335.7 5436.2 5437.5 5443.7 4407.9 4582.1 4824.6 4938.5 4946.5 3959.9 4011.0 4069.5 4129.0 4131.1 1194.7 1298.1 1299.5 1307.6 1311.5 898.0 915.8 918.9 930.8 951.0 5885.0 5896.9 5932.3 6017.4 6153.3 424.3 438.6 443.2 454.4 455.7 3921.5 3970.7 4044.0 4049.9 4051.6 10513.2 10817.5 10853.0 10928.6 10956.5 10889.0 10892.2 10894.6 10905.8 10909.8 7761.0 8310.1 8310.7 8416.5 8419.8 5836.1 6102.8 6119.7 6170.1 6194.8 9848.6 10178.8 10382.5 10388.0 10392.7 10267.5 10327.2 10457.4 10472.7 10476.8 7013.0 7293.9 7710.7 7730.7 7866.1 7124.2 8581.0 8672.7 8684.6 8698.7 7534.5 7614.0 7690.5 7767.3 7814.1 7415.7 7471.6 7479.3 7619.0 7628.2 6245.2 7144.2 7192.8 7202.6 7206.8 4632.3 4842.5 4916.5 4984.7 5064.2 5017.1 5054.4 5073.5 5119.2 5198.4 12:10:35 INFO - PROCESS | 1674 | 12:10:35 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4401.27 Median:4921.54 stddev:1377.95 (28.0%) stddev-sans-first:1347.13 12:10:35 INFO - PROCESS | 1674 | Values: 307.7 316.2 319.1 322.6 326.2 4650.3 5265.5 5286.3 5304.0 5325.7 4124.3 4789.6 4878.0 4948.8 5235.6 3317.9 3473.7 3990.2 4069.2 4079.6 3528.8 3953.7 4008.0 4067.8 4082.3 3323.6 3501.9 3627.6 3781.3 4014.9 3290.8 3743.4 3845.7 3911.9 3927.8 3195.3 3289.2 3490.9 3497.5 3501.8 4276.3 4532.2 4807.7 5129.9 5201.3 4224.5 4533.5 4596.0 4638.2 4664.3 5040.8 5534.7 5535.8 5555.7 5591.1 3218.6 3532.7 3808.3 3817.2 3831.9 4236.9 5415.0 5738.5 5870.4 5891.4 3232.6 3396.3 3791.0 3832.5 3848.5 3873.2 3884.6 3884.7 3895.2 3897.7 4535.7 4544.5 4545.8 4557.7 4561.9 5008.8 5309.9 5343.6 5385.7 5601.8 1073.7 1303.4 1317.5 1327.9 1328.4 729.9 778.9 787.9 808.7 819.9 4074.4 4111.6 4155.8 4663.8 5224.2 5015.0 5509.8 5605.5 5693.4 5742.9 4189.8 5131.3 5173.1 5215.2 5228.5 2484.7 2963.8 2974.9 3017.7 3026.7 4839.1 4968.7 5918.6 6018.5 6036.5 4745.0 4918.3 5512.4 5613.7 5837.0 4966.0 5141.9 5155.5 5358.7 5822.8 5203.4 5452.7 5688.5 5691.3 5720.0 4476.6 4782.5 5334.0 5608.3 5711.9 4638.4 5961.9 5982.4 5982.6 6011.3 3358.7 3367.2 3635.2 3792.1 3794.6 5194.4 5315.4 5619.9 5714.5 5722.4 4409.9 5069.1 5550.3 5607.9 5615.7 4580.4 5286.0 5478.5 5569.5 5598.6 5041.6 5275.5 5501.3 5517.0 5551.7 5260.4 5331.9 5489.7 5644.5 5662.7 4924.8 5496.6 5505.8 5546.1 5551.8 12:10:35 INFO - PROCESS | 1674 | 12:10:35 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:17918.28 Median:4745.18 stddev:23818.32 (501.9%) stddev-sans-first:23850.07 12:10:35 INFO - PROCESS | 1674 | Values: 705.3 781.3 828.6 846.8 857.4 15087.0 15440.2 15468.4 15469.4 15473.3 14046.8 14050.6 14086.4 14088.4 14109.3 709.0 740.0 887.7 901.7 919.8 899.4 912.9 917.1 918.1 918.5 972.8 973.9 979.4 979.8 980.2 751.9 780.9 782.4 783.1 976.4 679.4 706.5 734.9 735.2 735.6 14755.6 14762.9 14800.0 14805.3 14813.9 13413.0 13611.3 13624.3 13630.7 13879.9 12094.0 12260.1 12459.8 12612.8 12822.2 4562.0 4574.2 4585.4 4736.2 4754.2 74474.0 75146.9 75328.6 76442.9 76595.5 9905.5 10181.4 10891.5 10922.6 10923.2 21318.6 21428.6 21461.2 21468.6 21469.1 7770.7 7828.9 7965.8 8166.0 8167.6 4325.8 4404.7 4442.8 4495.3 4575.8 3420.2 3516.2 3516.7 3522.5 3525.6 653.2 662.4 665.2 677.9 678.1 559.5 659.1 665.9 671.1 686.1 8431.4 8500.2 9060.6 9112.9 9139.5 556.6 630.6 632.8 667.8 672.7 4402.6 4417.3 4621.4 4638.4 4717.5 78426.6 78557.0 79073.6 80282.0 81451.5 76480.5 77410.6 77568.0 77949.1 79657.0 50125.7 71192.6 71553.6 71612.0 71905.9 7172.4 9005.9 9034.7 9129.9 9132.8 45221.9 45892.8 46273.5 46392.0 46655.0 64300.7 64715.4 65079.7 65235.0 65238.7 10012.6 11657.5 12024.3 12269.3 12269.9 12696.6 12992.4 13344.5 13368.8 13418.1 12051.3 12122.2 12240.3 12241.9 12414.1 11874.2 12015.6 12108.2 12289.9 12453.6 11883.0 11910.8 11911.4 12107.9 12371.9 10286.6 10387.4 10400.2 10524.4 10641.3 9528.1 9744.0 10404.7 10483.4 10556.9 12:10:35 INFO - PROCESS | 1674 | -------- Summary: end -------- 12:10:35 INFO - PROCESS | 1674 | 12:10:36 INFO - TEST-INFO | 1674: exit 0 12:10:36 INFO - TEST-OK | dromaeo_css | took 1115112ms 12:10:36 INFO - TEST-START | kraken 12:10:36 INFO - operating with platform_type : mac_ 12:10:36 INFO - Initialising browser for kraken test... 12:10:36 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpcsAVyg/profile http://localhost:49275/getInfo.html) 12:10:38 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 12:10:38 INFO - PROCESS | 1683 | colorDepth:24 12:10:38 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 12:10:38 INFO - PROCESS | 1683 | __metrics 12:10:38 INFO - PROCESS | 1683 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:10:38 ERROR - PROCESS | 1683 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:10:39 INFO - TEST-INFO | 1683: exit 0 12:10:39 INFO - Browser initialized. 12:10:39 INFO - Running cycle 1/1 for kraken test... 12:10:39 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpcsAVyg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:51 INFO - PROCESS | 1684 | RSS: Main: 241975296 12:10:51 INFO - PROCESS | 1684 | 12:10:54 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 12:10:54 INFO - PROCESS | 1684 | RSS: Main: 291192832 12:10:54 INFO - PROCESS | 1684 | 12:10:57 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 12:10:58 INFO - PROCESS | 1684 | RSS: Main: 301162496 12:10:58 INFO - PROCESS | 1684 | 12:11:01 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 12:11:02 INFO - PROCESS | 1684 | RSS: Main: 386576384 12:11:02 INFO - PROCESS | 1684 | 12:11:04 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 12:11:05 INFO - PROCESS | 1684 | RSS: Main: 286818304 12:11:05 INFO - PROCESS | 1684 | 12:11:07 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:11:07 INFO - PROCESS | 1684 | RSS: Main: 291172352 12:11:07 INFO - PROCESS | 1684 | 12:11:10 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 12:11:11 INFO - PROCESS | 1684 | RSS: Main: 328601600 12:11:11 INFO - PROCESS | 1684 | 12:11:14 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 12:11:14 INFO - PROCESS | 1684 | RSS: Main: 329195520 12:11:14 INFO - PROCESS | 1684 | 12:11:17 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 12:11:18 INFO - PROCESS | 1684 | RSS: Main: 326815744 12:11:18 INFO - PROCESS | 1684 | 12:11:19 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:11:20 INFO - PROCESS | 1684 | RSS: Main: 309288960 12:11:20 INFO - PROCESS | 1684 | 12:11:22 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:11:22 INFO - PROCESS | 1684 | RSS: Main: 316186624 12:11:22 INFO - PROCESS | 1684 | 12:11:25 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:11:25 INFO - PROCESS | 1684 | RSS: Main: 319381504 12:11:25 INFO - PROCESS | 1684 | 12:11:28 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:11:29 INFO - PROCESS | 1684 | RSS: Main: 322412544 12:11:29 INFO - PROCESS | 1684 | 12:11:32 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:11:32 INFO - PROCESS | 1684 | RSS: Main: 322887680 12:11:32 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 12:11:34 INFO - PROCESS | 1684 | RSS: Main: 366673920 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | __start_tp_report 12:11:34 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 12:11:34 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 12:11:34 INFO - PROCESS | 1684 | |i|pagename|runs| 12:11:34 INFO - PROCESS | 1684 | |0;ai-astar;112;105;112;111;112;112;110;111;109;110 12:11:34 INFO - PROCESS | 1684 | |1;audio-beat-detection;155;123;116;191;122;120;139;117;151;116 12:11:34 INFO - PROCESS | 1684 | |2;audio-dft;182;185;185;182;191;264;185;180;186;185 12:11:34 INFO - PROCESS | 1684 | |3;audio-fft;80;72;57;105;57;56;62;64;80;57 12:11:34 INFO - PROCESS | 1684 | |4;audio-oscillator;84;96;92;71;72;68;95;70;69;130 12:11:34 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;87;83;86;82;86;83;87;83;86;83 12:11:34 INFO - PROCESS | 1684 | |6;imaging-darkroom;92;91;92;93;92;92;105;151;121;92 12:11:34 INFO - PROCESS | 1684 | |7;imaging-desaturate;86;85;83;85;85;83;91;84;83;85 12:11:34 INFO - PROCESS | 1684 | |8;json-parse-financial;57;56;55;55;53;53;54;55;55;54 12:11:34 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;64;64;79;75;72;70;70;69;69;69 12:11:34 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;89;107;109;106;82;84;84;88;126;101 12:11:34 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;143;149;158;152;150;148;144;140;150;141 12:11:34 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;171;167;166;166;167;167;165;164;168;162 12:11:34 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;66;67;67;66;64;65;67;65;65;67 12:11:34 INFO - PROCESS | 1684 | __end_tp_report 12:11:34 INFO - PROCESS | 1684 | __start_cc_report 12:11:34 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,2029 12:11:34 INFO - PROCESS | 1684 | __end_cc_report 12:11:34 INFO - PROCESS | 1684 | __startTimestamp1464808294870__endTimestamp 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | ------- Summary: start ------- 12:11:34 INFO - PROCESS | 1684 | Number of tests: 14 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.50 stddev:2.17 (1.9%) stddev-sans-first:2.22 12:11:34 INFO - PROCESS | 1684 | Values: 112.0 105.0 112.0 111.0 112.0 112.0 110.0 111.0 109.0 110.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:131.00 stddev:24.48 (18.7%) stddev-sans-first:24.87 12:11:34 INFO - PROCESS | 1684 | Values: 155.0 123.0 116.0 191.0 122.0 120.0 139.0 117.0 151.0 116.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:192.50 Median:185.00 stddev:25.29 (13.7%) stddev-sans-first:26.54 12:11:34 INFO - PROCESS | 1684 | Values: 182.0 185.0 185.0 182.0 191.0 264.0 185.0 180.0 186.0 185.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:69.00 Median:63.00 stddev:15.71 (24.9%) stddev-sans-first:16.15 12:11:34 INFO - PROCESS | 1684 | Values: 80.0 72.0 57.0 105.0 57.0 56.0 62.0 64.0 80.0 57.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:84.70 Median:78.00 stddev:19.52 (25.0%) stddev-sans-first:20.70 12:11:34 INFO - PROCESS | 1684 | Values: 84.0 96.0 92.0 71.0 72.0 68.0 95.0 70.0 69.0 130.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:84.60 Median:86.00 stddev:1.96 (2.3%) stddev-sans-first:1.87 12:11:34 INFO - PROCESS | 1684 | Values: 87.0 83.0 86.0 82.0 86.0 83.0 87.0 83.0 86.0 83.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:92.00 stddev:19.64 (21.4%) stddev-sans-first:20.49 12:11:34 INFO - PROCESS | 1684 | Values: 92.0 91.0 92.0 93.0 92.0 92.0 105.0 151.0 121.0 92.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:2.36 (2.8%) stddev-sans-first:2.47 12:11:34 INFO - PROCESS | 1684 | Values: 86.0 85.0 83.0 85.0 85.0 83.0 91.0 84.0 83.0 85.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.25 (2.3%) stddev-sans-first:1.01 12:11:34 INFO - PROCESS | 1684 | Values: 57.0 56.0 55.0 55.0 53.0 53.0 54.0 55.0 55.0 54.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.00 stddev:4.53 (6.5%) stddev-sans-first:4.24 12:11:34 INFO - PROCESS | 1684 | Values: 64.0 64.0 79.0 75.0 72.0 70.0 70.0 69.0 69.0 69.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:97.60 Median:83.00 stddev:14.48 (17.4%) stddev-sans-first:15.02 12:11:34 INFO - PROCESS | 1684 | Values: 89.0 107.0 109.0 106.0 82.0 84.0 84.0 88.0 126.0 101.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:149.50 stddev:5.54 (3.7%) stddev-sans-first:5.63 12:11:34 INFO - PROCESS | 1684 | Values: 143.0 149.0 158.0 152.0 150.0 148.0 144.0 140.0 150.0 141.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:167.00 stddev:2.41 (1.4%) stddev-sans-first:1.86 12:11:34 INFO - PROCESS | 1684 | Values: 171.0 167.0 166.0 166.0 167.0 167.0 165.0 164.0 168.0 162.0 12:11:34 INFO - PROCESS | 1684 | 12:11:34 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.50 stddev:1.10 (1.7%) stddev-sans-first:1.17 12:11:34 INFO - PROCESS | 1684 | Values: 66.0 67.0 67.0 66.0 64.0 65.0 67.0 65.0 65.0 67.0 12:11:34 INFO - PROCESS | 1684 | -------- Summary: end -------- 12:11:34 INFO - PROCESS | 1684 | 12:11:35 INFO - TEST-INFO | 1684: exit 0 12:11:35 INFO - TEST-OK | kraken | took 59399ms 12:11:35 INFO - SUITE-END | took 1174s 12:11:36 INFO - Completed test suite (00:19:34) 12:11:36 INFO - javascript benchmark 12:11:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3482.320947415254, 3482.6169121396642, 3492.284081763238, 3501.562304711911, 3549.716022718183, 5639.351474580424, 5682.119271512917, 5705.914411283824, 5792.420757924211, 5821.0, 5677.687727175004, 5720.2849643794525, 5826.184334193216, 5828.562857785672, 5869.1196320551835, 5092.439831618519, 5111.08000559899, 5185.351831021122, 5201.583873290147, 5294.364676238857, 5280.735963201843, 5308.973455132719, 5311.044012077844, 5335.573154147659, 5352.57179425645, 5105.566026887719, 5114.718690472023, 5155.994581056658, 5185.766640501187, 5211.296474975887, 5134.948650513485, 5161.200013997836, 5178.249153872705, 5221.164613661796, 5238.842834714965, 5159.71621560814, 5166.354205724285, 5173.456787037382, 5227.869303267411, 5250.501202385767, 5558.249636299089, 5614.578906581982, 5618.353889302742, 5680.31836179657, 5688.687122208277, 5258.7370631468275, 6229.4393504584805, 6266.154069200645, 6276.435120839101, 6289.685515724242, 6256.562040657156, 6324.956382196932, 6337.809865704036, 6344.587601805914, 6352.745890164388, 5645.435456454363, 5751.827445176651, 5754.741036653319, 5807.041838096667, 5816.47651711348, 6246.812595622138, 6292.056191571301, 6299.464545513682, 6304.0, 6313.842153946188, 6089.607648435413, 6152.138700581922, 6167.321594624587, 6230.190075290183, 6254.0, 6244.500439964792, 6311.715972781236, 6318.62088274705, 6318.905216421757, 6320.494360451153, 6112.877742445126, 6259.2488901332135, 6264.686765661699, 6283.59156654816, 6288.9056664150075, 6132.386102851662, 6149.708561202197, 6233.625982441068, 6248.812535623939, 6289.371062893674, 4944.233643785147, 4967.081089998362, 4974.602031837444, 4993.0, 5000.799968001312, 4414.874207077175, 4420.668449866209, 4428.778561071998, 4455.465344158722, 4487.282034874404, 5605.607607467438, 5683.77264909399, 5687.146927960839, 5692.829215123553, 5695.145728140812, 6227.532935029801, 6275.937240627627, 6280.403361680725, 6320.273168585672, 6338.0, 5705.600607957404, 5719.256496655408, 5722.484976352148, 5733.226014488074, 5770.798022069207, 5274.657147285414, 5324.387695414999, 5371.248025276464, 5384.300040994645, 5391.16436952273, 6258.812235632939, 6328.114064030948, 6372.235331760033, 6378.425941665179, 6379.712912918837, 6282.591631543935, 6287.779927702509, 6295.087212354275, 6302.810915672537, 6322.304546499973, 5085.618578606545, 6248.187735594343, 6264.624122552479, 6363.872722545616, 6379.52153588492, 6211.3478084802255, 6214.937850621436, 6215.720292586754, 6218.502519798496, 6223.844403889758, 6273.1844860167885, 6284.277308109626, 6298.937010629835, 6298.937010629835, 6321.051842223703, 5812.9709351452975, 6091.969540152271, 6104.48947159306, 6106.3283038866575, 6139.584134579286, 6092.603980741238, 6152.692365381802, 6158.04550294705, 6164.137020817179, 6168.784092556739, 5998.550108741949, 6003.789867354796, 6036.8188954331445, 6048.395160484092, 6071.726772295169, 5952.107183922446, 6096.878062438637, 6139.171211886338, 6160.7535698571555, 6188.504919606511, 6029.3669164737075, 6037.305709843425, 6044.395560444096, 6048.274207095177, 6076.544259180668], "value": 5721.550353901093, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.1005245584176, 509.17259453388266, 510.06657816196355, 530.694850460985, 534.927784749059, 30715.539266911015, 31624.0, 32568.67431325686, 41689.0, 42635.36046959298, 44711.77644111775, 45390.773046134804, 45402.7729861351, 45460.54539454605, 45598.0, 9517.571709273083, 9762.26782991275, 9766.0, 9775.804483910317, 9776.120149186572, 9449.76375590609, 9614.990426005274, 9627.518624068804, 9629.903700963006, 9632.325737198398, 9575.186109180693, 9613.0, 9621.422714637145, 9622.134007939285, 9632.277579181555, 9538.761530961712, 9604.51977401127, 9605.615775369046, 9611.951940240324, 9659.5170241488, 9518.095780900803, 9623.133917947349, 9629.181519570844, 9632.422054676741, 9669.226461883103, 41658.79170604158, 41973.0, 42098.15803683909, 42116.1576768466, 42130.78934605338, 3620.5655321361605, 3638.945415818738, 3802.714796390254, 3839.9616003840165, 4435.866923992286, 31434.68565314363, 32210.71157153711, 32434.513482297785, 32714.6728532714, 32798.3440331192, 3262.902112936592, 3360.092774950774, 3367.478040903664, 3405.540252065989, 3433.4678124890684, 503450.0, 503906.0, 504535.0, 506773.0, 508975.45512272575, 62455.68772156201, 63310.73378532404, 63428.048579271344, 63500.0, 63529.682351587944, 66163.33836661668, 66789.9981500278, 66817.66591167013, 66835.66582167058, 66998.0, 13986.86013139876, 16670.499885003468, 16880.409185678443, 16923.238454269587, 16977.745333820003, 13048.673783155307, 13116.868831311565, 13129.146605470805, 13129.474821007243, 13139.474421023053, 16736.6610671146, 17046.403375881786, 17166.742498862528, 17167.65664686699, 17222.0, 6494.902576461357, 6540.4767618590395, 6542.149520562297, 6554.639494827735, 6601.240857301375, 916.7204002779083, 953.7043516509904, 1047.6490375724065, 1094.8795632480421, 1101.4107452512867, 24917.380370275874, 29493.0, 29561.704382956326, 30309.93915212957, 30673.38653226923, 985.8077674853334, 1134.3364131982883, 1156.2195518025298, 1206.6983254186453, 1222.6123349997638, 12589.55936542235, 15809.604759880865, 15837.445689400816, 16283.511494655178, 16284.592885177964, 563458.0, 675248.6237568879, 675327.0, 678114.0, 684379.0, 61663.766724665256, 61828.0, 61833.763324733256, 61860.07209891855, 62143.37856621466, 1270.8919741822049, 1339.0961101256612, 1398.867107624774, 1433.4911106557022, 1445.4218312675014, 27819.16542503647, 31529.52705709462, 31546.211344716565, 31799.364012720078, 32155.0, 51166.48833511617, 51351.0, 52085.7395713019, 52222.0, 52237.47762522478, 487593.0, 493141.0, 494010.0, 495532.0, 498364.0, 35260.64739352574, 35701.64298356983, 41633.79183104065, 43114.56885431105, 43217.35173972456, 49050.0, 49857.50142498528, 50501.21240906873, 50590.7352316195, 50736.0, 37433.81283093567, 38492.0, 40296.798516007235, 40928.79535602303, 41235.587644123174, 39541.0, 39543.0, 39941.0, 40266.597334026286, 40750.59249407468, 38375.0, 38861.0, 39132.60867391404, 39405.802970984965, 39444.61943831952, 33126.668733312355, 34646.30707385889, 35511.46732798959, 35616.46575301424, 35647.10882227965, 22832.31503054911, 24809.00763969421, 25463.98144074216, 25476.0, 25736.144914814937], "value": 20457.247351004484, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.34514497926017, 386.65201318813064, 391.72018850291283, 395.50759304665695, 416.6958120571982, 19788.90105549472, 19994.50013749657, 20910.89544552275, 20998.370048898538, 21000.36998890032, 17307.82692173078, 19956.60086798263, 20731.585368292624, 21367.893160534215, 21826.0, 5235.1885457753915, 5766.826995190151, 5943.613665111776, 5973.163757074013, 5975.581709280352, 5848.649081055129, 5863.677497737623, 5896.233489646362, 5953.464188223059, 5960.374160713128, 5833.649981001132, 5890.440408161217, 5908.763649454017, 5910.763569457216, 5938.910916336258, 5690.97562438765, 5863.472287494124, 5929.911051334234, 5941.257342832146, 6061.787837425669, 5861.70691465425, 5873.765049398019, 5879.0593505039415, 5895.793647222326, 5898.056310990264, 13269.203847769164, 13342.466301347935, 23021.96401161952, 24476.877615611986, 25703.97184112633, 2157.4822042709793, 2158.2554018863466, 2295.3343530376337, 2764.156932135698, 2816.690164081949, 9963.65127220551, 10019.0, 10462.738431539196, 12795.93602031984, 15010.024348417319, 1896.421591414618, 1926.3931861463593, 2021.878687278768, 2064.1743302678897, 2093.204582258747, 100762.0, 113400.0, 114765.0, 117647.0, 120237.0, 11344.886551134548, 11495.137864660184, 11588.13089018327, 11647.941760291144, 11750.529978800923, 14580.489682861049, 14666.633334166518, 14690.0, 14714.117152970855, 14728.779068313985, 8500.74497765068, 8689.391742578086, 8690.08754080825, 8791.428557143892, 8825.955870220609, 8566.27186689126, 8580.65677372898, 8605.13948605147, 8697.739067827975, 8729.345299102593, 4462.6388951370145, 4555.40779698637, 4580.335851301543, 4616.492185859553, 4635.142498637762, 1869.9158537865828, 1911.3405874973112, 1919.500929758245, 1925.9422217333502, 1964.0965156028387, 865.523961820996, 914.8764916736288, 916.3951791817368, 937.5921474158763, 954.370115723619, 17323.740143897852, 19305.420837374902, 20904.790952090283, 22054.0, 23138.884305578365, 965.7826988927576, 975.7658162041201, 995.5967833027636, 1037.113268155716, 1040.6409788622914, 11174.26881955911, 11566.710832229262, 11684.298942063333, 11718.414079296, 11727.530898764124, 501758.0, 502954.0, 504533.4773326183, 596150.0, 602550.0, 98132.50933745428, 102030.0, 139541.72062257223, 139933.0, 141920.29039854734, 988.4316518002033, 996.6013594562233, 1030.1295405382589, 1031.4533297352416, 1032.3341444768232, 15555.84444155544, 18948.43154705361, 19200.903995479934, 19274.8072519273, 19366.0, 42781.09549538732, 53676.0, 53803.46196538141, 54216.72891635517, 55175.724121379135, 74942.0, 76560.85158722736, 76804.0, 78095.0, 79133.20866791258, 7434.3309102181065, 7962.203779622223, 8038.83922321562, 8061.072976607765, 8380.790480238044, 9162.541872906462, 12399.512058553031, 12549.372531373578, 13160.341982900625, 13173.077884547989, 10350.585976561155, 11200.43997800123, 11601.883981160418, 11644.475998579916, 11841.5855445059, 10776.461176941279, 11074.501647425725, 11190.160737944523, 11504.101823199078, 11917.0, 6625.536212465081, 7923.643436045507, 11444.885551144382, 11536.653900383002, 12019.879801201876, 7871.960640196762, 8192.631331590212, 8236.50580965144, 8319.584020798815, 8418.621162047844, 7139.82150446243, 11024.228304018641, 11722.648320550397, 11746.17776755619, 11910.344931028876], "value": 9559.101729330476, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.1023324605762, 1560.0561660195585, 1585.254930182814, 1829.1768704083158, 1971.8126777956097, 7929.5638739869355, 8720.956395218023, 8922.509261990588, 8928.642854285821, 9049.321300902426, 8651.04838467768, 8870.467771933689, 8872.822543549126, 8876.0, 8887.0, 1730.974035389467, 2092.403664955488, 2303.527776805751, 2306.746257911628, 2388.0925248405583, 2398.412388964706, 2428.7449817769143, 2429.866357350345, 2430.3195105370523, 2433.4159801647565, 2363.2083252110565, 2382.2376839411395, 2402.495475950044, 2403.3631087761755, 2409.8674572898485, 2067.390119914629, 2139.4116617930194, 2141.764405915348, 2156.54712510373, 2351.5884720173985, 2333.2766842278947, 2408.9518209635708, 2410.2166795791436, 2416.0818888822137, 2417.407735104893, 8894.911050889537, 8971.372003959725, 8973.057828927998, 8979.326550508738, 8997.550122493849, 4504.6396288296855, 5269.947300527022, 5434.972825135849, 5436.238926550286, 5440.755166017539, 3578.8031658258788, 3757.0231739747587, 3757.0607348162957, 3771.7925514096714, 3772.1135533149686, 1070.6948519671935, 1095.2497539185683, 1103.2001798695962, 1105.4638500327335, 1125.7073160978123, 12076.396180191043, 12082.214656047328, 12087.03303735699, 12095.455704493319, 12127.151099423045, 4738.620910327166, 4778.498257682893, 4879.878003049952, 4880.267959805987, 4889.975550122226, 9948.751281218027, 10110.545025473872, 10115.797684046278, 10122.190224782, 10151.187904967584, 4493.460784705856, 5335.706536140549, 5436.157395603688, 5437.537809286176, 5443.673379597237, 4407.911841763147, 4582.106489234568, 4824.638152138604, 4938.530839570235, 4946.455889852114, 3959.92080158401, 4011.0, 4069.4709687740997, 4128.979355103205, 4131.091159944807, 1194.7013246688327, 1298.0524217321408, 1299.5451591942744, 1307.6338625184962, 1311.4688551136614, 897.9910200897907, 915.7756349694234, 918.8621706744042, 930.7853251506849, 951.048951048951, 5884.9705751470965, 5896.850114227685, 5932.2584676915385, 6017.3681763414215, 6153.261608606996, 424.331677607769, 438.63812354807413, 443.18675230951567, 454.3593533118353, 455.731118640004, 3921.509811273591, 3970.741901776433, 4043.959560404417, 4049.8785036448953, 4051.5745846686264, 10513.211509136692, 10817.513211905483, 10853.0, 10928.617498387519, 10956.506957186786, 10888.965548272901, 10892.237543371888, 10894.564217431373, 10905.83641245382, 10909.83635245472, 7760.961195194101, 8310.085890552155, 8310.667573297122, 8416.537090460084, 8419.83160336802, 5836.124581312837, 6102.786402475892, 6119.724612392364, 6170.074488826712, 6194.783182588588, 9848.556814943488, 10178.796424071625, 10382.480875956324, 10388.0, 10392.740181495523, 10267.486625668538, 10327.173826094046, 10457.372557646566, 10472.68581942543, 10476.79046419052, 7013.018177455262, 7293.927060729325, 7710.730124445618, 7730.652120654697, 7866.056073271244, 7124.216336203117, 8581.01318348398, 8672.739817805223, 8684.609192586222, 8698.739037828875, 7534.4725869190825, 7614.010178676736, 7690.538567685957, 7767.300942915164, 7814.101378341564, 7415.703371865065, 7471.589062601609, 7479.326860582573, 7619.0, 7628.160902300853, 6245.18812554365, 7144.24985376528, 7192.820179495555, 7202.559488102505, 7206.783796486114, 4632.2819962905805, 4842.5157484251295, 4916.508349165055, 4984.651074424901, 5064.18972964324, 5017.096922553829, 5054.444011158843, 5073.492650735045, 5119.232115182753, 5198.376194856642], "value": 4547.201025429955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.6892338737875, 316.1858215096128, 319.05718601532476, 322.55648483335415, 326.1617642658369, 4650.3024546318065, 5265.473452654733, 5286.312779338686, 5304.0, 5325.733713314334, 4124.340105583107, 4789.563131060677, 4877.951220487794, 4948.802047918079, 5235.55497782689, 3317.871923545994, 3473.687368136867, 3990.221906728189, 4069.2065047315787, 4079.551249362568, 3528.8412021459094, 3953.655757042605, 4008.0, 4067.776272305022, 4082.3060079786505, 3323.60116785986, 3501.859925602973, 3627.6372362763645, 3781.3193625147464, 4014.899627509321, 3290.8025518468967, 3743.419769935664, 3845.653891149782, 3911.8630847920326, 3927.7643341399607, 3195.265089029513, 3289.227031647561, 3490.912727181815, 3497.475378693191, 3501.7918818007747, 4276.294411422112, 4532.229520981441, 4807.711537307773, 5129.948700512984, 5201.349831271091, 4224.471941007374, 4533.546645335473, 4595.954040459586, 4638.165130276549, 4664.253719404879, 5040.84877453677, 5534.723263836832, 5535.750891209906, 5555.722213889329, 5591.133374326945, 3218.6459489456142, 3532.6820586147114, 3808.333541630204, 3817.2174704185577, 3831.9042023949346, 4236.85171019016, 5414.999999999961, 5738.4835364816945, 5870.385643947863, 5891.381404952461, 3232.62402134431, 3396.3377141457177, 3790.962090379061, 3832.5400951885395, 3848.461215429842, 3873.2447172801035, 3884.592117827644, 3884.6503814656817, 3895.162540053902, 3897.7271590988926, 4535.659825513099, 4544.522825103382, 4545.818167273272, 4557.726536407826, 4561.885952851139, 5008.799648014038, 5309.893802124013, 5343.625946183807, 5385.727181693088, 5601.831945041656, 1073.7100982734619, 1303.4134639412114, 1317.5059352742721, 1327.9070465067352, 1328.4287756264898, 729.9488736219827, 778.9298963093349, 787.9244830805909, 808.720991258015, 819.8685813577225, 4074.3684728867074, 4111.568285330057, 4155.8337666493, 4663.766811659403, 5224.205050298072, 5014.974925125351, 5509.807156749494, 5605.467480589337, 5693.437952921202, 5742.856428589235, 4189.790510474464, 5131.281620573122, 5173.1464308388695, 5215.24378965048, 5228.503292187236, 2484.6582845263356, 2963.8215911305174, 2974.917557709612, 3017.7325523279837, 3026.697330267, 4839.080574690797, 4968.677035992649, 5918.644881307136, 6018.488428483541, 6036.547258955596, 4744.976275118672, 4918.2786024891, 5512.4211957743855, 5613.726440791486, 5837.0, 4965.999999999928, 5141.945901090267, 5155.484451554815, 5358.713908661815, 5822.7670893163795, 5203.375594928632, 5452.700101494456, 5688.488036076773, 5691.345495267931, 5720.0, 4476.597106260452, 4782.545658162469, 5334.013207556614, 5608.298962629508, 5711.942880571307, 4638.443386793607, 5961.880762384815, 5982.4316689914385, 5982.611130276518, 6011.27864656244, 3358.7480938930166, 3367.1582104473882, 3635.2190574211277, 3792.108854419154, 3794.5636251830288, 5194.428612852659, 5315.4418786028455, 5619.943800562106, 5714.514266287412, 5722.396309679003, 4409.867703968886, 5069.138246498031, 5550.333959924835, 5607.943920560742, 5615.747291371817, 4580.381648477412, 5286.0, 5478.45215478449, 5569.498745112959, 5598.552115830806, 5041.554845899663, 5275.472452754694, 5501.257330260363, 5516.972415137899, 5551.722413879371, 5260.368755749335, 5331.946680533145, 5489.670619762827, 5644.52021578154, 5662.66024038559, 4924.803007879645, 5496.615236933376, 5505.779768809202, 5546.140348246028, 5551.806361632269], "value": 3965.929068480509, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.3299365602678, 781.2968328504344, 828.5567221536479, 846.7502086884368, 857.3526987124724, 15086.999999999985, 15440.150791706452, 15468.38126474941, 15469.381224750996, 15473.30370133344, 14046.789298160536, 14050.578482645511, 14086.366113524891, 14088.36602352894, 14109.294535273246, 708.9822754431154, 740.0194741966916, 887.6626881784945, 901.6934242357597, 919.8022425178585, 899.3524662243175, 912.8585069314267, 917.1058218237232, 918.1277786103228, 918.4672889723978, 972.7568107973007, 973.9172170365528, 979.4319294808994, 979.7746518300795, 980.2403137568406, 751.868423025971, 780.9297163255334, 782.3936449251876, 783.0759703549809, 976.4287891583432, 679.4224908827506, 706.4595584377955, 734.8787450070732, 735.2280105888796, 735.6138027535533, 14755.631109222355, 14762.852371476256, 14799.964002519828, 14805.333759980827, 14813.851861481355, 13413.0, 13611.251381174032, 13624.318784060757, 13630.659233519242, 13879.861201387957, 12094.048200699985, 12260.080493962989, 12459.813102803466, 12612.81080783789, 12822.230666159967, 4561.954380456219, 4574.245249533823, 4585.403897493305, 4736.171170045228, 4754.191787396151, 74474.0, 75146.8727969081, 75328.62335688286, 76442.85335719968, 76595.46809063843, 9905.455199964072, 10181.440020798778, 10891.509882055327, 10922.617708380327, 10923.235373523936, 21318.57362852734, 21428.57142857134, 21461.248856289956, 21468.570628587655, 21469.14123435076, 7770.689172433153, 7828.858441111428, 7965.800854978672, 8165.959170204111, 8167.63245653947, 4325.783710814447, 4404.66964977628, 4442.777861106932, 4495.303227999665, 4575.816967321336, 3420.2133509293003, 3516.191276006533, 3516.7362447816004, 3522.454019626962, 3525.629808870083, 653.150903825035, 662.3906006474056, 665.1785045468843, 677.9017042528905, 678.1015154919751, 559.5383808358121, 659.120074700271, 665.8934570468701, 671.1475733913235, 686.080651926421, 8431.367647426345, 8500.192481714228, 9060.592273347715, 9112.8633070504, 9139.49732764691, 556.6381851796365, 630.5964182922933, 632.7975047984693, 667.7829705345782, 672.7409947170277, 4402.603765661106, 4417.277261867874, 4621.445426548836, 4638.397008388888, 4717.481077081518, 78426.6078669603, 78557.0, 79073.60463197762, 80282.0, 81451.48388128936, 76480.47039059187, 77410.61294693491, 77568.0, 77949.0512737186, 79657.0, 50125.74937125291, 71192.64403678052, 71553.64223178955, 71612.0, 71905.92141117888, 7172.39034682037, 9005.909940900769, 9034.728958131267, 9129.863052054357, 9132.817343652956, 45221.869453263935, 45892.77053614711, 46273.53726462827, 46392.0, 46655.0, 64300.678496607215, 64715.35284647093, 65079.67460162669, 65235.0, 65238.673806630664, 10012.64955726546, 11657.475413606238, 12024.33866137371, 12269.325187114795, 12269.877301226874, 12696.555620553236, 12992.415341309852, 13344.466221351036, 13368.799468007794, 13418.060735748404, 12051.33717645538, 12122.151449398458, 12240.265584064984, 12241.877581224073, 12414.068944828994, 11874.228175168586, 12015.639530814089, 12108.21296615717, 12289.938550307192, 12453.626391208278, 11883.0, 11910.821337679768, 11911.40442977865, 12107.939460302641, 12371.938140309241, 10286.588536458758, 10387.428691421743, 10400.219983501116, 10524.421156836448, 10641.308314959502, 9528.052511212029, 9743.951280243553, 10404.739881503023, 10483.423411712429, 10556.947215263874], "value": 7178.460875221282, "unit": "score"}], "name": "dromaeo_css", "value": 7246.992694488776, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 112.0, 111.0, 112.0, 112.0, 110.0, 111.0, 109.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 116.0, 191.0, 122.0, 120.0, 139.0, 117.0, 151.0, 116.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 185.0, 182.0, 191.0, 264.0, 185.0, 180.0, 186.0, 185.0], "value": 192.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 72.0, 57.0, 105.0, 57.0, 56.0, 62.0, 64.0, 80.0, 57.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 96.0, 92.0, 71.0, 72.0, 68.0, 95.0, 70.0, 69.0, 130.0], "value": 84.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 82.0, 86.0, 83.0, 87.0, 83.0, 86.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 93.0, 92.0, 92.0, 105.0, 151.0, 121.0, 92.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 83.0, 85.0, 85.0, 83.0, 91.0, 84.0, 83.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 55.0, 53.0, 53.0, 54.0, 55.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 79.0, 75.0, 72.0, 70.0, 70.0, 69.0, 69.0, 69.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 107.0, 109.0, 106.0, 82.0, 84.0, 84.0, 88.0, 126.0, 101.0], "value": 97.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 149.0, 158.0, 152.0, 150.0, 148.0, 144.0, 140.0, 150.0, 141.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 167.0, 166.0, 166.0, 167.0, 167.0, 165.0, 164.0, 168.0, 162.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 64.0, 65.0, 67.0, 65.0, 65.0, 67.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1465.4, "alertThreshold": 2.0}]} 12:11:36 INFO - Return code: 0 12:11:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:11:36 ERROR - # TBPL SUCCESS # 12:11:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:11:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:11:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:11:36 INFO - 2016-06-01 12:11:36.162 screenresolution[1688:10629] starting screenresolution argv=screenresolution get 12:11:36 INFO - 2016-06-01 12:11:36.165 screenresolution[1688:10629] Display 0: 1600x1200x32@60 12:11:36 INFO - 2016-06-01 12:11:36.171 screenresolution[1689:10632] starting screenresolution argv=screenresolution list 12:11:36 INFO - Available Modes on Display 0 12:11:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:11:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:11:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:11:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:11:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:11:36 INFO - Intel Iris: 12:11:36 INFO - Chipset Model: Intel Iris 12:11:36 INFO - Type: GPU 12:11:36 INFO - Bus: Built-In 12:11:36 INFO - VRAM (Dynamic, Max): 1536 MB 12:11:36 INFO - Vendor: Intel (0x8086) 12:11:36 INFO - Device ID: 0x0a2e 12:11:36 INFO - Revision ID: 0x0009 12:11:36 INFO - Displays: 12:11:36 INFO - Display: 12:11:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:11:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:11:36 INFO - Display Serial Number: 1600x1200 60 12:11:36 INFO - Main Display: Yes 12:11:36 INFO - Mirror: Off 12:11:36 INFO - Online: Yes 12:11:36 INFO - Rotation: Supported 12:11:36 INFO - Return code: 0 12:11:36 INFO - Running post-action listener: _resource_record_post_action 12:11:36 INFO - ##### 12:11:36 INFO - ##### Finished run-tests step (success) 12:11:36 INFO - ##### 12:11:36 INFO - Running post-run listener: _resource_record_post_run 12:11:36 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31694336; Write bytes: 699605504; Read time: 204; Write time: 1909 12:11:36 INFO - TinderboxPrint: CPU usage
53.8% 12:11:36 INFO - TinderboxPrint: I/O read bytes / time
31,694,336 / 204 12:11:36 INFO - TinderboxPrint: I/O write bytes / time
699,605,504 / 1,909 12:11:36 INFO - TinderboxPrint: CPU idle
2,239.0 (46.2%) 12:11:36 INFO - TinderboxPrint: CPU system
968.8 (20.0%) 12:11:36 INFO - TinderboxPrint: CPU user
1,633.9 (33.7%) 12:11:36 INFO - TinderboxPrint: Swap in / out
427,905,024 / 0 12:11:36 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376648192; Write bytes: 371951104; Read time: 28123; Write time: 759 12:11:36 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30764544; Write bytes: 297303040; Read time: 191; Write time: 1082 12:11:36 INFO - Running post-run listener: _upload_blobber_files 12:11:36 INFO - Blob upload gear active. 12:11:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:11:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:11:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:11:37 INFO - (blobuploader) - INFO - Open directory for files ... 12:11:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:11:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:11:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:11:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:11:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:11:38 INFO - (blobuploader) - INFO - Done attempting. 12:11:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:11:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:11:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:11:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:11:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:11:39 INFO - (blobuploader) - INFO - Done attempting. 12:11:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:11:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:11:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:11:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:11:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:11:42 INFO - (blobuploader) - INFO - Done attempting. 12:11:42 INFO - (blobuploader) - INFO - Iteration through files over. 12:11:42 INFO - Return code: 0 12:11:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:11:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:11:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6cc2e3898749c03c4cebd3c0180535ebce3b935a3ab70b2a07947aaa2984679918fd66f44120850e396aee907c308678e8c370e51460d5dbed351a96924bdf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cb6a50209f5dd9e7f350fa8e5e0e32dd98fab09f1bc5241f0a116c189e312936f9aa1e9923de99be94a3c3c48f60196493dff6b01e77a2f04f176272a48f979", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24f3be9b3fbf303fb15d619b6d1b840a61782c521d673e11ac93f8fa8a779efa9c05feb1dcc4753d59cc3beab9cd2205e06b1358ef0db08a501338c439363f00"} 12:11:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:11:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:11:42 INFO - Contents: 12:11:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6cc2e3898749c03c4cebd3c0180535ebce3b935a3ab70b2a07947aaa2984679918fd66f44120850e396aee907c308678e8c370e51460d5dbed351a96924bdf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cb6a50209f5dd9e7f350fa8e5e0e32dd98fab09f1bc5241f0a116c189e312936f9aa1e9923de99be94a3c3c48f60196493dff6b01e77a2f04f176272a48f979", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24f3be9b3fbf303fb15d619b6d1b840a61782c521d673e11ac93f8fa8a779efa9c05feb1dcc4753d59cc3beab9cd2205e06b1358ef0db08a501338c439363f00"} 12:11:42 INFO - Running post-run listener: copy_logs_to_upload_dir 12:11:42 INFO - Copying logs to upload dir... 12:11:42 INFO - mkdir: /builds/slave/test/build/upload/logs 12:11:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.656233 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-06-01 12:11:42.083942) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 12:11:42.087192) ========= 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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6cc2e3898749c03c4cebd3c0180535ebce3b935a3ab70b2a07947aaa2984679918fd66f44120850e396aee907c308678e8c370e51460d5dbed351a96924bdf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cb6a50209f5dd9e7f350fa8e5e0e32dd98fab09f1bc5241f0a116c189e312936f9aa1e9923de99be94a3c3c48f60196493dff6b01e77a2f04f176272a48f979", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24f3be9b3fbf303fb15d619b6d1b840a61782c521d673e11ac93f8fa8a779efa9c05feb1dcc4753d59cc3beab9cd2205e06b1358ef0db08a501338c439363f00"} build_url:https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011010 build_url: 'https://queue.taskcluster.net/v1/task/eMf78d3iQUahcYA4GjQ7QQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e6cc2e3898749c03c4cebd3c0180535ebce3b935a3ab70b2a07947aaa2984679918fd66f44120850e396aee907c308678e8c370e51460d5dbed351a96924bdf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1cb6a50209f5dd9e7f350fa8e5e0e32dd98fab09f1bc5241f0a116c189e312936f9aa1e9923de99be94a3c3c48f60196493dff6b01e77a2f04f176272a48f979", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/24f3be9b3fbf303fb15d619b6d1b840a61782c521d673e11ac93f8fa8a779efa9c05feb1dcc4753d59cc3beab9cd2205e06b1358ef0db08a501338c439363f00"}' ========= master_lag: 0.63 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 12:11:42.732255) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 12:11:42.732772) ========= 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.zkJWAjUSUm/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.rlw0uKJ90q/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.004533 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 12:11:42.781958) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 12:11:42.782280) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 12:11:42.782665) ========= ========= Total master_lag: 0.82 =========