builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0144 starttime: 1463425715.45 results: success (0) buildid: 20160516100743 builduid: 9d7e1a4fb13741329457ec273bb2e093 revision: 5bdcb5693f01c712b82da8b63e1c83abf5e743e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:35.446018) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:35.446433) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:35.446700) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:35.465028) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:35.465282) ========= 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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-16 12:08:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 148M=0s 2016-05-16 12:08:35 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132936 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:35.614954) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:35.615232) ========= 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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.020376 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:35.666597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 12:08:35.666891) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5bdcb5693f01c712b82da8b63e1c83abf5e743e8 --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 5bdcb5693f01c712b82da8b63e1c83abf5e743e8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-16 12:08:35,685 truncating revision to first 12 chars 2016-05-16 12:08:35,685 Setting DEBUG logging. 2016-05-16 12:08:35,685 attempt 1/10 2016-05-16 12:08:35,685 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5bdcb5693f01?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 12:08:37,294 unpacking tar archive at: fx-team-5bdcb5693f01/testing/mozharness/ program finished with exit code 0 elapsedTime=1.847880 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 12:08:37.534944) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:37.535261) ========= script_repo_revision: 5bdcb5693f01c712b82da8b63e1c83abf5e743e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:37.538657) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:37.538916) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 12:08:37.555730) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-16 12:08:37.556001) ========= /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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:08:37 INFO - MultiFileLogger online at 20160516 12:08:37 in /builds/slave/test 12:08:37 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 12:08:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:37 INFO - {'append_to_log': False, 12:08:37 INFO - 'base_work_dir': '/builds/slave/test', 12:08:37 INFO - 'blob_upload_branch': 'Fx-Team', 12:08:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:37 INFO - 'branch': 'Fx-Team', 12:08:37 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:37 INFO - 'config_files': ('talos/mac_config.py',), 12:08:37 INFO - 'default_actions': ('clobber', 12:08:37 INFO - 'read-buildbot-config', 12:08:37 INFO - 'download-and-extract', 12:08:37 INFO - 'populate-webroot', 12:08:37 INFO - 'create-virtualenv', 12:08:37 INFO - 'install', 12:08:37 INFO - 'run-tests'), 12:08:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:37 INFO - 'download_minidump_stackwalk': True, 12:08:37 INFO - 'download_symbols': 'ondemand', 12:08:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:08:37 INFO - 'tooltool.py': '/tools/tooltool.py', 12:08:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:08:37 INFO - '/tools/misc-python/virtualenv.py')}, 12:08:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:37 INFO - 'installer_path': 'installer.exe', 12:08:37 INFO - 'log_level': 'info', 12:08:37 INFO - 'log_name': 'talos', 12:08:37 INFO - 'log_to_console': True, 12:08:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:08:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:08:37 INFO - 'opt_config_files': (), 12:08:37 INFO - 'pip_index': False, 12:08:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:37 INFO - 'cmd': ('bash', 12:08:37 INFO - '-c', 12:08:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:37 INFO - 'enabled': True, 12:08:37 INFO - 'halt_on_failure': False, 12:08:37 INFO - 'name': 'check_screen_resolution'},), 12:08:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:37 INFO - 'cmd': ('bash', 12:08:37 INFO - '-c', 12:08:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:37 INFO - 'enabled': True, 12:08:37 INFO - 'halt_on_failure': False, 12:08:37 INFO - 'name': 'check_screen_resolution'},), 12:08:37 INFO - 'run_cmd_checks_enabled': True, 12:08:37 INFO - 'sps_profile': False, 12:08:37 INFO - 'sps_profile_interval': 0, 12:08:37 INFO - 'suite': 'dromaeojs', 12:08:37 INFO - 'system_bits': '32', 12:08:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:37 INFO - 'title': 't-yosemite-r7-0144', 12:08:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:37 INFO - 'use_talos_json': True, 12:08:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:37 INFO - 'work_dir': 'build'} 12:08:37 INFO - ##### 12:08:37 INFO - ##### Running clobber step. 12:08:37 INFO - ##### 12:08:37 INFO - Running pre-action listener: _resource_record_pre_action 12:08:37 INFO - Running main action method: clobber 12:08:37 INFO - rmtree: /builds/slave/test/build 12:08:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:38 INFO - Running post-action listener: _resource_record_post_action 12:08:38 INFO - ##### 12:08:38 INFO - ##### Finished clobber step (success) 12:08:38 INFO - ##### 12:08:38 INFO - ##### 12:08:38 INFO - ##### Running read-buildbot-config step. 12:08:38 INFO - ##### 12:08:38 INFO - Running pre-action listener: _resource_record_pre_action 12:08:38 INFO - Running main action method: read_buildbot_config 12:08:38 INFO - Using buildbot properties: 12:08:38 INFO - { 12:08:38 INFO - "project": "", 12:08:38 INFO - "product": "firefox", 12:08:38 INFO - "script_repo_revision": "production", 12:08:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:08:38 INFO - "repository": "", 12:08:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:08:38 INFO - "buildid": "20160516100743", 12:08:38 INFO - "pgo_build": "False", 12:08:38 INFO - "basedir": "/builds/slave/test", 12:08:38 INFO - "buildnumber": 818, 12:08:38 INFO - "slavename": "t-yosemite-r7-0144", 12:08:38 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:08:38 INFO - "platform": "yosemite_r7", 12:08:38 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:08:38 INFO - "branch": "fx-team", 12:08:38 INFO - "repo_path": "integration/fx-team", 12:08:38 INFO - "revision": "5bdcb5693f01c712b82da8b63e1c83abf5e743e8", 12:08:38 INFO - "stage_platform": "macosx64", 12:08:38 INFO - "builduid": "9d7e1a4fb13741329457ec273bb2e093", 12:08:38 INFO - "slavebuilddir": "test" 12:08:38 INFO - } 12:08:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 12:08:38 INFO - Running post-action listener: _resource_record_post_action 12:08:38 INFO - ##### 12:08:38 INFO - ##### Finished read-buildbot-config step (success) 12:08:38 INFO - ##### 12:08:38 INFO - ##### 12:08:38 INFO - ##### Running download-and-extract step. 12:08:38 INFO - ##### 12:08:38 INFO - Running pre-action listener: _resource_record_pre_action 12:08:38 INFO - Running main action method: download_and_extract 12:08:38 INFO - mkdir: /builds/slave/test/build/tests 12:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:38 INFO - https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:08:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:08:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:08:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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 12:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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 12:08:41 INFO - Downloaded 1199 bytes. 12:08:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 12:08:41 INFO - Using the following test package requirements: 12:08:41 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:08:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:08:41 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 12:08:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:08:41 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 12:08:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:08:41 INFO - u'jsshell-mac.zip'], 12:08:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:08:41 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 12:08:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:08:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:08:41 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 12:08:41 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:08:41 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 12:08:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:08:41 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 12:08:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:08:41 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 12:08:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:41 INFO - https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:08:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:08:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:08:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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 12:08:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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 12:08:42 INFO - Downloaded 18267728 bytes. 12:08:42 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'] 12:08:42 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 12:08:45 INFO - Return code: 0 12:08:45 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 12:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:45 INFO - https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:08:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:08:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:08:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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 12:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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 12:08:47 INFO - Downloaded 18267728 bytes. 12:08:47 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'] 12:08:47 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 12:08:50 INFO - Return code: 0 12:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:50 INFO - https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:08:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:08:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:08:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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 12:08:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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 12:08:51 INFO - Downloaded 10840194 bytes. 12:08:51 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'] 12:08:51 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 12:08:51 INFO - Return code: 0 12:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:52 INFO - https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:08:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:08:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:08:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 12:08:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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 12:08:55 INFO - Downloaded 110051301 bytes. 12:08:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:08:55 INFO - mkdir: /builds/slave/test/properties 12:08:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:08:55 INFO - Writing to file /builds/slave/test/properties/build_url 12:08:55 INFO - Contents: 12:08:55 INFO - build_url:https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:08:56 INFO - Running post-action listener: _resource_record_post_action 12:08:56 INFO - Running post-action listener: set_extra_try_arguments 12:08:56 INFO - ##### 12:08:56 INFO - ##### Finished download-and-extract step (success) 12:08:56 INFO - ##### 12:08:56 INFO - ##### 12:08:56 INFO - ##### Running populate-webroot step. 12:08:56 INFO - ##### 12:08:56 INFO - Running pre-action listener: _resource_record_pre_action 12:08:56 INFO - Running main action method: populate_webroot 12:08:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:08:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:08:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:08:56 INFO - '--tppagecycles', 12:08:56 INFO - '7'], 12:08:56 INFO - 'tests': ['tsvgm']}}, 12:08:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:08:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:08:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:08:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:08:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['damp', 'tps']}, 12:08:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:56 INFO - 'tests': ['damp', 'tps']}, 12:08:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['dromaeo_dom']}, 12:08:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:08:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other-e10s': {'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['a11yr', 12:08:56 INFO - 'ts_paint', 12:08:56 INFO - 'tpaint', 12:08:56 INFO - 'sessionrestore', 12:08:56 INFO - 'sessionrestore_no_auto_restore', 12:08:56 INFO - 'tabpaint']}, 12:08:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['tsvgx', 12:08:56 INFO - 'tsvgr_opacity', 12:08:56 INFO - 'tart', 12:08:56 INFO - 'tscrollx', 12:08:56 INFO - 'cart']}, 12:08:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:08:56 INFO - 'tsvgr_opacity', 12:08:56 INFO - 'tart', 12:08:56 INFO - 'tscrollx', 12:08:56 INFO - 'cart']}, 12:08:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-e10s'], 12:08:56 INFO - 'tests': ['tp5o']}, 12:08:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:56 INFO - 'tests': ['tp5o']}, 12:08:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:56 INFO - 'talos_options': ['--disable-e10s', 12:08:56 INFO - '--xperf_path', 12:08:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:56 INFO - 'tests': ['tp5n']}, 12:08:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:56 INFO - 'talos_options': ['--xperf_path', 12:08:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:56 INFO - 'tests': ['tp5n']}}, 12:08:56 INFO - 'talos.zip': {'path': '', 12:08:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:08:56 INFO - Running post-action listener: _resource_record_post_action 12:08:56 INFO - ##### 12:08:56 INFO - ##### Finished populate-webroot step (success) 12:08:56 INFO - ##### 12:08:56 INFO - ##### 12:08:56 INFO - ##### Running create-virtualenv step. 12:08:56 INFO - ##### 12:08:56 INFO - Running pre-action listener: _resource_record_pre_action 12:08:56 INFO - Running main action method: create_virtualenv 12:08:56 INFO - Creating virtualenv /builds/slave/test/build/venv 12:08:56 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 12:08:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:08:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:08:56 INFO - Using real prefix '/tools/python27' 12:08:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:08:57 INFO - Installing distribute.............................................................................................................................................................................................done. 12:09:00 INFO - Installing pip.................done. 12:09:00 INFO - Return code: 0 12:09:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:09:00 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')]} 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:00 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')]} 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:00 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 0x101fb1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd88400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, '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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:09:00 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 12:09:00 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 12:09:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:09:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:00 INFO - 'HOME': '/Users/cltbld', 12:09:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:00 INFO - 'LOGNAME': 'cltbld', 12:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:00 INFO - 'MOZ_NO_REMOTE': '1', 12:09:00 INFO - 'NO_EM_RESTART': '1', 12:09:00 INFO - 'PAGER': '/bin/cat', 12:09:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:00 INFO - 'PWD': '/builds/slave/test', 12:09:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:00 INFO - 'SHELL': '/bin/bash', 12:09:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:09:00 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:09:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:00 INFO - 'USER': 'cltbld', 12:09:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:00 INFO - 'XPC_FLAGS': '0x0', 12:09:00 INFO - 'XPC_SERVICE_NAME': '0', 12:09:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:00 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 12:09:00 INFO - Cleaning up... 12:09:00 INFO - Return code: 0 12:09:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:09:00 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')]} 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:00 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')]} 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:00 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 0x101fb1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd88400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, '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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:09:00 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 12:09:00 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 12:09:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:09:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:00 INFO - 'HOME': '/Users/cltbld', 12:09:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:00 INFO - 'LOGNAME': 'cltbld', 12:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:00 INFO - 'MOZ_NO_REMOTE': '1', 12:09:00 INFO - 'NO_EM_RESTART': '1', 12:09:00 INFO - 'PAGER': '/bin/cat', 12:09:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:00 INFO - 'PWD': '/builds/slave/test', 12:09:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:00 INFO - 'SHELL': '/bin/bash', 12:09:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:09:00 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:09:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:00 INFO - 'USER': 'cltbld', 12:09:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:00 INFO - 'XPC_FLAGS': '0x0', 12:09:00 INFO - 'XPC_SERVICE_NAME': '0', 12:09:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:00 INFO - Downloading/unpacking psutil>=3.1.1 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:09:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:03 INFO - Installing collected packages: psutil 12:09:03 INFO - Running setup.py install for psutil 12:09:03 INFO - building 'psutil._psutil_osx' extension 12:09:03 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 12:09:03 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 12:09:03 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 12:09:03 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 12:09:03 INFO - building 'psutil._psutil_posix' extension 12:09:03 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 12:09:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:09:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:09:03 INFO - ^ 12:09:03 INFO - 1 warning generated. 12:09:03 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 12:09:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:03 INFO - Successfully installed psutil 12:09:03 INFO - Cleaning up... 12:09:04 INFO - Return code: 0 12:09:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:04 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 0x101fb1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd88400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, '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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:09:04 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 12:09:04 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 12:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:04 INFO - 'HOME': '/Users/cltbld', 12:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:04 INFO - 'LOGNAME': 'cltbld', 12:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:04 INFO - 'MOZ_NO_REMOTE': '1', 12:09:04 INFO - 'NO_EM_RESTART': '1', 12:09:04 INFO - 'PAGER': '/bin/cat', 12:09:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:04 INFO - 'PWD': '/builds/slave/test', 12:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:04 INFO - 'SHELL': '/bin/bash', 12:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:09:04 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:04 INFO - 'USER': 'cltbld', 12:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:04 INFO - 'XPC_FLAGS': '0x0', 12:09:04 INFO - 'XPC_SERVICE_NAME': '0', 12:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:09:06 INFO - Installing collected packages: mozsystemmonitor 12:09:06 INFO - Running setup.py install for mozsystemmonitor 12:09:06 INFO - Successfully installed mozsystemmonitor 12:09:06 INFO - Cleaning up... 12:09:07 INFO - Return code: 0 12:09:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:07 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 0x101fb1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd88400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, '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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:09:07 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 12:09:07 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 12:09:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:09:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:07 INFO - 'HOME': '/Users/cltbld', 12:09:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:07 INFO - 'LOGNAME': 'cltbld', 12:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:07 INFO - 'MOZ_NO_REMOTE': '1', 12:09:07 INFO - 'NO_EM_RESTART': '1', 12:09:07 INFO - 'PAGER': '/bin/cat', 12:09:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:07 INFO - 'PWD': '/builds/slave/test', 12:09:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:07 INFO - 'SHELL': '/bin/bash', 12:09:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:09:07 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:09:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:07 INFO - 'USER': 'cltbld', 12:09:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:07 INFO - 'XPC_FLAGS': '0x0', 12:09:07 INFO - 'XPC_SERVICE_NAME': '0', 12:09:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:07 INFO - Downloading/unpacking blobuploader==1.2.4 12:09:07 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 12:09:07 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 12:09:07 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 12:09:07 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 12:09:07 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 12:09:07 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 12:09:09 INFO - Downloading blobuploader-1.2.4.tar.gz 12:09:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:09:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:09:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:10 INFO - Downloading docopt-0.6.1.tar.gz 12:09:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:09:10 INFO - Installing collected packages: blobuploader, requests, docopt 12:09:10 INFO - Running setup.py install for blobuploader 12:09:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:09:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:09:11 INFO - Running setup.py install for requests 12:09:11 INFO - Running setup.py install for docopt 12:09:11 INFO - Successfully installed blobuploader requests docopt 12:09:11 INFO - Cleaning up... 12:09:11 INFO - Return code: 0 12:09:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:11 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 0x101fb1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd88400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, '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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:09:11 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 12:09:11 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 12:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:11 INFO - 'HOME': '/Users/cltbld', 12:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:11 INFO - 'LOGNAME': 'cltbld', 12:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:11 INFO - 'MOZ_NO_REMOTE': '1', 12:09:11 INFO - 'NO_EM_RESTART': '1', 12:09:11 INFO - 'PAGER': '/bin/cat', 12:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:11 INFO - 'PWD': '/builds/slave/test', 12:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:11 INFO - 'SHELL': '/bin/bash', 12:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:09:11 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:11 INFO - 'USER': 'cltbld', 12:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:11 INFO - 'XPC_FLAGS': '0x0', 12:09:11 INFO - 'XPC_SERVICE_NAME': '0', 12:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-g1Tv1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-EPMfsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-DS8Dzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-3lsx4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-YF99fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-tweZMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-6BVXG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-qslut2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-jNuLUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:12 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-lFaaJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:13 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-xxFkpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:13 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-DI1Edu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:13 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-JIuUof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:13 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-utiXLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:13 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-mexWz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:13 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-lFhVfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:13 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-kvqWY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:09:13 INFO - Running setup.py install for manifestparser 12:09:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:09:13 INFO - Running setup.py install for mozcrash 12:09:14 INFO - Running setup.py install for mozdebug 12:09:14 INFO - Running setup.py install for mozdevice 12:09:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:09:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:14 INFO - Running setup.py install for mozfile 12:09:14 INFO - Running setup.py install for mozhttpd 12:09:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:14 INFO - Running setup.py install for mozinfo 12:09:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:14 INFO - Running setup.py install for mozInstall 12:09:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:15 INFO - Running setup.py install for mozleak 12:09:15 INFO - Running setup.py install for mozlog 12:09:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:15 INFO - Running setup.py install for moznetwork 12:09:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:15 INFO - Running setup.py install for mozprocess 12:09:15 INFO - Running setup.py install for mozprofile 12:09:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:15 INFO - Running setup.py install for mozrunner 12:09:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:15 INFO - Running setup.py install for mozscreenshot 12:09:16 INFO - Running setup.py install for moztest 12:09:16 INFO - Running setup.py install for mozversion 12:09:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:16 INFO - Cleaning up... 12:09:16 INFO - Return code: 0 12:09:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:16 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 0x101fb1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd88400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, '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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:09:16 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 12:09:16 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 12:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:16 INFO - 'HOME': '/Users/cltbld', 12:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:16 INFO - 'LOGNAME': 'cltbld', 12:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:16 INFO - 'MOZ_NO_REMOTE': '1', 12:09:16 INFO - 'NO_EM_RESTART': '1', 12:09:16 INFO - 'PAGER': '/bin/cat', 12:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:16 INFO - 'PWD': '/builds/slave/test', 12:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:16 INFO - 'SHELL': '/bin/bash', 12:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:09:16 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:16 INFO - 'USER': 'cltbld', 12:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:16 INFO - 'XPC_FLAGS': '0x0', 12:09:16 INFO - 'XPC_SERVICE_NAME': '0', 12:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:16 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-dmMlv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:16 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)) 12:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:16 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-G96Fhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:16 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)) 12:09:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:16 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-ZeGgjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-r7ryCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-Zmm8DL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-cYWt3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-CYonae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-8hrLUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-7EvLK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-l4hfnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-HF78m0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-Cr42WW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:17 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)) 12:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:17 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-VO08Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:18 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)) 12:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-IDUAVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:18 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)) 12:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-SLuz_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:18 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)) 12:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-ygSuiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:18 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)) 12:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:18 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-26o1TS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 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)) 12:09:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:20 INFO - Downloading blessings-1.6.tar.gz 12:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:20 INFO - Installing collected packages: blessings 12:09:20 INFO - Running setup.py install for blessings 12:09:21 INFO - Successfully installed blessings 12:09:21 INFO - Cleaning up... 12:09:21 INFO - Return code: 0 12:09:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:21 INFO - Reading from file tmpfile_stdout 12:09:21 INFO - Current package versions: 12:09:21 INFO - blessings == 1.6 12:09:21 INFO - blobuploader == 1.2.4 12:09:21 INFO - docopt == 0.6.1 12:09:21 INFO - manifestparser == 1.1 12:09:21 INFO - mozInstall == 1.12 12:09:21 INFO - mozcrash == 0.17 12:09:21 INFO - mozdebug == 0.1 12:09:21 INFO - mozdevice == 0.48 12:09:21 INFO - mozfile == 1.2 12:09:21 INFO - mozhttpd == 0.7 12:09:21 INFO - mozinfo == 0.9 12:09:21 INFO - mozleak == 0.1 12:09:21 INFO - mozlog == 3.2 12:09:21 INFO - moznetwork == 0.27 12:09:21 INFO - mozprocess == 0.22 12:09:21 INFO - mozprofile == 0.28 12:09:21 INFO - mozrunner == 6.11 12:09:21 INFO - mozscreenshot == 0.1 12:09:21 INFO - mozsystemmonitor == 0.3 12:09:21 INFO - moztest == 0.7 12:09:21 INFO - mozversion == 1.4 12:09:21 INFO - psutil == 3.1.1 12:09:21 INFO - requests == 1.2.3 12:09:21 INFO - wsgiref == 0.1.2 12:09:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:21 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 0x101fb1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd88400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, '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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:09:21 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 12:09:21 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 12:09:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:09:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:21 INFO - 'HOME': '/Users/cltbld', 12:09:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:21 INFO - 'LOGNAME': 'cltbld', 12:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:21 INFO - 'MOZ_NO_REMOTE': '1', 12:09:21 INFO - 'NO_EM_RESTART': '1', 12:09:21 INFO - 'PAGER': '/bin/cat', 12:09:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:21 INFO - 'PWD': '/builds/slave/test', 12:09:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:21 INFO - 'SHELL': '/bin/bash', 12:09:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:09:21 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:09:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:21 INFO - 'USER': 'cltbld', 12:09:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:21 INFO - 'XPC_FLAGS': '0x0', 12:09:21 INFO - 'XPC_SERVICE_NAME': '0', 12:09:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:21 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)) 12:09:21 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)) 12:09:21 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)) 12:09:21 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)) 12:09:21 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)) 12:09:21 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)) 12:09:21 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)) 12:09:21 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)) 12:09:21 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)) 12:09:21 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)) 12:09:21 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)) 12:09:21 INFO - Cleaning up... 12:09:21 INFO - Return code: 0 12:09:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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')]} 12:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fb1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd88400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, '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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:09: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', 'jsonschema'] in /builds/slave/test/build 12:09: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 jsonschema 12:09:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:09:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:21 INFO - 'HOME': '/Users/cltbld', 12:09:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:21 INFO - 'LOGNAME': 'cltbld', 12:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:21 INFO - 'MOZ_NO_REMOTE': '1', 12:09:21 INFO - 'NO_EM_RESTART': '1', 12:09:21 INFO - 'PAGER': '/bin/cat', 12:09:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:21 INFO - 'PWD': '/builds/slave/test', 12:09:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:21 INFO - 'SHELL': '/bin/bash', 12:09:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:09:21 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:09:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:21 INFO - 'USER': 'cltbld', 12:09:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:21 INFO - 'XPC_FLAGS': '0x0', 12:09:21 INFO - 'XPC_SERVICE_NAME': '0', 12:09:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:22 INFO - Downloading/unpacking jsonschema 12:09:22 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 12:09:22 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 12:09:22 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 12:09:22 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 12:09:22 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 12:09:22 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 12:09:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:09:24 INFO - Downloading/unpacking functools32 (from jsonschema) 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09:24 INFO - Downloading functools32-3.2.3-2.tar.gz 12:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:09:25 INFO - warning: no files found matching '*.txt' 12:09:25 INFO - no previously-included directories found matching 'build' 12:09:25 INFO - no previously-included directories found matching 'dist' 12:09:25 INFO - no previously-included directories found matching '.git*' 12:09:25 INFO - Installing collected packages: jsonschema, functools32 12:09:25 INFO - Running setup.py install for functools32 12:09:25 INFO - warning: no files found matching '*.txt' 12:09:25 INFO - no previously-included directories found matching 'build' 12:09:25 INFO - no previously-included directories found matching 'dist' 12:09:25 INFO - no previously-included directories found matching '.git*' 12:09:25 INFO - Successfully installed jsonschema functools32 12:09:25 INFO - Cleaning up... 12:09:25 INFO - Return code: 0 12:09:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101fb1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fb7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0abd88400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, '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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 12:09:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:09:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:09:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:25 INFO - 'HOME': '/Users/cltbld', 12:09:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:25 INFO - 'LOGNAME': 'cltbld', 12:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:25 INFO - 'MOZ_NO_REMOTE': '1', 12:09:25 INFO - 'NO_EM_RESTART': '1', 12:09:25 INFO - 'PAGER': '/bin/cat', 12:09:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:25 INFO - 'PWD': '/builds/slave/test', 12:09:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:25 INFO - 'SHELL': '/bin/bash', 12:09:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:09:25 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:09:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:25 INFO - 'USER': 'cltbld', 12:09:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:25 INFO - 'XPC_FLAGS': '0x0', 12:09:25 INFO - 'XPC_SERVICE_NAME': '0', 12:09:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:25 INFO - Downloading/unpacking flake8 12:09:25 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 12:09:25 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 12:09:25 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 12:09:25 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 12:09:25 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 12:09:25 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 12:09:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:09:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:09:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 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 12:09:28 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 12:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:09:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:09:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:09:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:09:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:09:29 INFO - no previously-included directories found matching 'docs/_build' 12:09:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:09:29 INFO - Running setup.py install for pep8 12:09:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:09:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:09:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:09:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:09:29 INFO - no previously-included directories found matching 'docs/_build' 12:09:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:09:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:09:29 INFO - Cleaning up... 12:09:29 INFO - Return code: 0 12:09:29 INFO - Running post-action listener: _resource_record_post_action 12:09:29 INFO - Running post-action listener: _start_resource_monitoring 12:09:29 INFO - Starting resource monitoring. 12:09:29 INFO - ##### 12:09:29 INFO - ##### Finished create-virtualenv step (success) 12:09:29 INFO - ##### 12:09:29 INFO - ##### 12:09:29 INFO - ##### Running install step. 12:09:29 INFO - ##### 12:09:29 INFO - Running pre-action listener: _resource_record_pre_action 12:09:29 INFO - Running main action method: install 12:09:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:29 INFO - Reading from file tmpfile_stdout 12:09:29 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:30 INFO - Reading from file tmpfile_stdout 12:09:30 INFO - Output received: 12:09:30 INFO - Usage: mozinstall [options] installer 12:09:30 INFO - Options: 12:09:30 INFO - -h, --help show this help message and exit 12:09:30 INFO - -d DEST, --destination=DEST 12:09:30 INFO - Directory to install application into. [default: 12:09:30 INFO - "/builds/slave/test"] 12:09:30 INFO - --app=APP Application being installed. [default: firefox] 12:09:30 INFO - mkdir: /builds/slave/test/build/application 12:09:30 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'] 12:09:30 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 12:10:03 INFO - Reading from file tmpfile_stdout 12:10:03 INFO - Output received: 12:10:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:10:03 INFO - Running post-action listener: _resource_record_post_action 12:10:03 INFO - ##### 12:10:03 INFO - ##### Finished install step (success) 12:10:03 INFO - ##### 12:10:03 INFO - ##### 12:10:03 INFO - ##### Running run-tests step. 12:10:03 INFO - ##### 12:10:03 INFO - Running pre-action listener: _resource_record_pre_action 12:10:03 INFO - Running main action method: run_tests 12:10:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:10:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:10:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:10:03 INFO - 2016-05-16 12:10:03.032 screenresolution[1955:13021] starting screenresolution argv=screenresolution get 12:10:03 INFO - 2016-05-16 12:10:03.035 screenresolution[1955:13021] Display 0: 1600x1200x32@60 12:10:03 INFO - 2016-05-16 12:10:03.042 screenresolution[1956:13023] starting screenresolution argv=screenresolution list 12:10:03 INFO - Available Modes on Display 0 12:10:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:10:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:10:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:10:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:10:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:10:03 INFO - Intel Iris: 12:10:03 INFO - Chipset Model: Intel Iris 12:10:03 INFO - Type: GPU 12:10:03 INFO - Bus: Built-In 12:10:03 INFO - VRAM (Dynamic, Max): 1536 MB 12:10:03 INFO - Vendor: Intel (0x8086) 12:10:03 INFO - Device ID: 0x0a2e 12:10:03 INFO - Revision ID: 0x0009 12:10:03 INFO - Displays: 12:10:03 INFO - Display: 12:10:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:10:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:10:03 INFO - Display Serial Number: 1600x1200 60 12:10:03 INFO - Main Display: Yes 12:10:03 INFO - Mirror: Off 12:10:03 INFO - Online: Yes 12:10:03 INFO - Rotation: Supported 12:10:03 INFO - Return code: 0 12:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:10:03 INFO - Python 2.7.3 12:10:03 INFO - Return code: 0 12:10:03 INFO - grabbing minidump binary from tooltool 12:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:03 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 0x101e55d30>, '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 0x101c79db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e4cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:10:03 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 12:10:03 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 12:10:03 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 12:10:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:10:03 INFO - Return code: 0 12:10:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:10:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:10:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:10:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:10:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:10:04 INFO - Return code: 0 12:10:04 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/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--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 12:10:04 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/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0144 --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 12:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Z9Reb6wCR/Render', 12:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:04 INFO - 'HOME': '/Users/cltbld', 12:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:04 INFO - 'LOGNAME': 'cltbld', 12:10:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:10:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:04 INFO - 'MOZ_NO_REMOTE': '1', 12:10:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:10:04 INFO - 'NO_EM_RESTART': '1', 12:10:04 INFO - 'PAGER': '/bin/cat', 12:10:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:04 INFO - 'PWD': '/builds/slave/test', 12:10:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:04 INFO - 'SHELL': '/bin/bash', 12:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKLZdRpzRB/Listeners', 12:10:04 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 12:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:04 INFO - 'USER': 'cltbld', 12:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:04 INFO - 'XPC_FLAGS': '0x0', 12:10:04 INFO - 'XPC_SERVICE_NAME': '0', 12:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:04 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/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--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 12:10:04 INFO - mozversion application_buildid: 20160516100743 12:10:04 INFO - mozversion application_changeset: 5bdcb5693f01c712b82da8b63e1c83abf5e743e8 12:10:04 INFO - mozversion application_display_name: Nightly 12:10:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:10:04 INFO - mozversion application_name: Firefox 12:10:04 INFO - mozversion application_remotingname: firefox 12:10:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:10:04 INFO - mozversion application_vendor: Mozilla 12:10:04 INFO - mozversion application_version: 49.0a1 12:10:04 INFO - mozversion platform_buildid: 20160516100743 12:10:04 INFO - mozversion platform_changeset: 5bdcb5693f01c712b82da8b63e1c83abf5e743e8 12:10:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:10:04 INFO - mozversion platform_version: 49.0a1 12:10:04 INFO - using testdate: 1463425804 12:10:04 INFO - actual date: 1463425804 12:10:04 INFO - starting webserver on 'localhost:49527' 12:10:04 INFO - SUITE-START | Running 2 tests 12:10:04 INFO - TEST-START | dromaeo_css 12:10:04 INFO - operating with platform_type : mac_ 12:10:04 INFO - Initialising browser for dromaeo_css test... 12:10:04 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpWw7P6L/profile http://localhost:49527/getInfo.html) 12:10:06 INFO - PROCESS | 1974 | __metrics Screen width/height:1600/1200 12:10:06 INFO - PROCESS | 1974 | colorDepth:24 12:10:06 INFO - PROCESS | 1974 | Browser inner width/height: 1024/689 12:10:06 INFO - PROCESS | 1974 | __metrics 12:10:06 INFO - PROCESS | 1974 | 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:06 ERROR - PROCESS | 1974 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:10:07 INFO - TEST-INFO | 1974: exit 0 12:10:07 INFO - Browser initialized. 12:10:07 INFO - Running cycle 1/1 for dromaeo_css test... 12:10:07 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpWw7P6L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:19 INFO - PROCESS | 1976 | RSS: Main: 239243264 12:10:19 INFO - PROCESS | 1976 | 12:11:10 INFO - PROCESS | 1976 | 1463425870591 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:13:22 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49527/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49527/tests/dromaeo/cssquery-ext.html) 12:13:23 INFO - PROCESS | 1976 | RSS: Main: 344891392 12:13:23 INFO - PROCESS | 1976 | 12:16:26 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49527/tests/dromaeo/cssquery-ext.html (next: http://localhost:49527/tests/dromaeo/cssquery-jquery.html) 12:16:27 INFO - PROCESS | 1976 | RSS: Main: 371982336 12:16:27 INFO - PROCESS | 1976 | 12:19:29 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49527/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49527/tests/dromaeo/cssquery-mootools.html) 12:19:30 INFO - PROCESS | 1976 | RSS: Main: 370147328 12:19:30 INFO - PROCESS | 1976 | 12:22:32 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49527/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49527/tests/dromaeo/cssquery-prototype.html) 12:22:33 INFO - PROCESS | 1976 | RSS: Main: 371789824 12:22:33 INFO - PROCESS | 1976 | 12:25:36 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49527/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49527/tests/dromaeo/cssquery-yui.html) 12:25:37 INFO - PROCESS | 1976 | RSS: Main: 336814080 12:25:37 INFO - PROCESS | 1976 | 12:28:40 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49527/tests/dromaeo/cssquery-yui.html (next: http://localhost:49527/tests/dromaeo/cssquery-dojo.html) 12:28:40 INFO - PROCESS | 1976 | RSS: Main: 374198272 12:28:40 INFO - PROCESS | 1976 | 12:28:40 INFO - PROCESS | 1976 | __start_tp_report 12:28:40 INFO - PROCESS | 1976 | _x_x_mozilla_page_load 12:28:40 INFO - PROCESS | 1976 | _x_x_mozilla_page_load_details 12:28:40 INFO - PROCESS | 1976 | |i|pagename|runs| 12:28:40 INFO - PROCESS | 1976 | |0;dojo.html;3377.071305391017;3377.5609170807793;3403.3193361327735;3414.3683418567584;3456.239627281997;5569.275994120763;5572.944270557293;5606.299212598426;5657.2645556077705;5657.575681823864;5610.074337734269;5664;5678.971605141979;5681.886362272752;5701.2018317435595;5054.3429354184;5094.337736094302;5121.462246464123;5122.897542049157;5181.222816577506;5151.995360904625;5176.896462070757;5184.455632158626;5185.092608793465;5187.19598462239;5071.746412679351;5087.720175390352;5109.540141387274;5116.565091967187;5181.53366197044;5154.664946778447;5196.9740151299;5202.479752024805;5203.297554830087;5249.265102885599;5007.824726134568;5018.623603229736;5049.267856160836;5113.054084994288;5119.488051194888;4638.8608341749805;5531.3362396512675;5536.086545719952;5558.416366281563;5586.078297080977;6109.14471973924;6128.479079278267;6151.015837465984;6152;6167.229096362955;6090.299615544225;6123.081537769326;6169.167162433059;6216.471599914013;6252;4793.9280910786365;5723.484886360247;5775.451332123442;5811.506021988093;5812.534997200214;6206.534509911775;6220.191375121205;6225.1907252057845;6237.968810156011;6250.781222657276;4934.383202099738;6029.8191054268445;6088.756449742049;6109.327973922865;6122.479589234876;5196.8960620787375;6198.783042593487;6200.7519699211525;6231.844203894848;6234.625922444668;6125.540584456184;6154.353792851775;6167.136600875881;6186.938130618636;6193.1329613854095;6054.061620448838;6106.582013105394;6115.327313995457;6119.938800611937;6143.139960405547;4766.952330476651;4779.761011949388;4786.617070634341;4810.13417584838;4814.8925747078365;4365.80353884076;4387.473503179639;4400.405945197422;4405.713628614129;4406.228909940747;4769.403824521934;5575.219469274305;5654.06707893193;5667.433256674382;5699.373068962411;6205.503559715212;6222.595531290543;6238;6254;6261.029540421242;5552;5640.5487560995025;5666.631668941588;5674.687892165888;5685.4883060523925;5246.160614301693;5280.762365693553;5319.361676598755;5328.333958255218;5337.733113344318;6215.502759779117;6219.844503887439;6228.128062071313;6237.251529816451;6254.155688982111;6121.143039974496;6228.56400051992;6244.250689917239;6278.780242691484;6293.181886354744;5648.293963254593;5908.645481271137;6160.13758073861;6183.628982261079;6250.468710159687;6110.572259941761;6121.265448146251;6133.325334213144;6135.509159267337;6163.476104531165;4722.716637001791;6071.392860713893;6243.625382477066;6247.406496382836;6255.906161407674;6003.369646187262;6043.667598282137;6046.546509011754;6068.939310606837;6080.2399700037495;6051.485623722033;6055.424734650202;6113.174721412553;6113.29697084841;6114.908276375769;5820.185174075717;5895.233619629421;5952.5833191676165;5965.194698715618;5979.970100149471;6046.183765191819;6109.34393638171;6116.640127388606;6119.722872950242;6121.574916084701;5930.940690593211;5947.137665038632;5947.405259474018;5954.225950626391;5980.760769569342 12:28:40 INFO - PROCESS | 1976 | |1;ext.html;502.62805523912306;514.6397521734784;536.6511767351221;543.7607452720803;549.6619578958941;31154.2211444714;31321.68678313216;32537.861174858885;32602.836985815036;43550.782246088806;43009.78495107528;45554.54445455544;45601;45656.54343456565;45776.313355299615;9755.853662195055;9955.153811925995;9956.153726933184;9960;9965.75085622858;8079.272865442109;8791.64833406663;8951.597178127;8964.955175224115;9701.563429645648;8848.823023539526;8908.109189081104;11741.706457338549;11985.041196704242;11993.040556755439;11864.406779661025;11948.820767688492;11976.341301228429;11990;12011.93994030033;8697.69558065471;11587.594434194763;11694.532218711327;11708.94619484242;11725.94137029318;41039.76880693584;41710;41790.1641967162;43329.566704332865;43433.34849977253;3261.8369081546066;3429.262708517656;3481.721462283011;3530.3292374448774;3672.926541469156;28844.71155288441;28971.855140724372;29316.853415733;29824.552631710543;30122.397552048835;2712.742289482496;2723.8093333466663;2732.3442373830335;2762.2541913683194;2816.380396312808;528438;530652;531400.7729574871;531697;532769;35859.4621080684;36731.510506501836;36798.63201368006;63705.044424333675;63781.68109159517;37476;37515.62484375176;37633;37650.811745941646;37695.24609507794;12864.871351286554;15277;15369.769453458208;15417.460388886335;15437.691246175014;12919.354032298348;13011.609651710463;13035.283059431633;13040.152390094614;13045.412956416794;7747.767566973;7916.643751031217;8215.794605134919;8243.711470098518;8294.751157465285;3332.483465062919;3393.0499460150704;3401.6428275030767;3429;3449.2411669432686;952.9809403811885;979.329159525713;999.4203362049993;1044.0081922173965;1118.927269727465;29323.706762932525;29448.11655650334;29830.70169298279;30037;30071.248218794266;1121.3720316622716;1121.9775604487866;1179.6402097360387;1207.2334067866793;1230.034422977958;15271.38914443432;15283.312250948735;15296.235188240766;15644.217789110726;15668.843311566967;701330;706570;713143;713627;718629.4068529728;54721;55798.721006394706;62318;63314;63641.681791590745;1337.338017681254;1479.179055624139;1500.0324790510278;1504.593759684857;1509;29764.553531697475;30153.849230753705;30657.846710767197;30879;31166.532502012033;53850;53857;54337.18494222511;54340.184897227366;54418;626035;651129;678943;679008;881447;26619.733802661725;26859.865700671373;26900.865495672395;26909.192724218305;26961.595576066768;45756.542434575225;46215.76892115518;46642.766786165856;46679.53320466888;47685.76157119192;36345.27309453849;37464.812675936446;37893.05267368338;38519;38550.6144938547;33114.50328245127;36430.63569364379;36533.817330913174;37011;38619.8414047579;36848.07879803026;37166.628333717395;37658.8117059413;37802.243955121296;37865.81067094647;26053.609195862457;31778.841105794323;33024.50463243102;33182.336353273284;33392.83303583564;21549.353519394444;24222.87888560546;24620.50758984846;24899.87550062238;25493.87253063723 12:28:40 INFO - PROCESS | 1976 | |2;jquery.html;393.5611792850971;397.57062372637546;401.24365570898846;408.39761352005775;428.83918530551045;17269.22288497019;20079.498012549695;20254.594908101866;21229.150833966643;21411.250606228787;20554.177832886668;20853;21394.25120120796;21756.45608859779;21881.234156804516;5879.88240235195;6112.449879510843;6167.198264225644;6167.321594624587;6174.289956654998;5867.735951882176;6067.0899365095365;6127;6129.478994285469;6218.906716399258;6251.593646412968;6252.562320637558;6265.404786545271;6270.686465676721;6287.151234583318;6234.875302493947;6308.747650094014;6978.371946524786;6980.162380514371;6996.475264355168;5301;6091.817245482643;6103.328633850273;6124.693765311717;6127.20346354971;14097.859021409757;14115.435382584685;19290.74610150336;25554.616680749805;26066;1922.9423117306503;2056.8289806904713;2061.134323584098;2141.282670305434;2226.699395581592;9936;10002.149817265468;10416.270861039655;10611.257211995164;14495.71008579833;1756.8506676932477;1768.2750072470353;1768.9833589707744;1769.3630293094475;1774.1306759687789;85467.57266213628;107598;111194;113529.43235283853;116407.83592164055;11433.31400115979;11759.529618815322;11787.99802016821;11798.233114847508;11949.669814386285;14760;15209.771853422208;15354.616134596725;15416;15416.845831541541;7711.768646940601;7920.2871741542385;8349.582520873932;8812.383133180616;9016.819663606691;8620.224179823846;8688.044315125331;8927.196552310323;9006.181692794455;9018;4636.652251081115;4648.604868586141;4735.715857048588;4735.881602959954;4824.831130910471;1737.3342953751333;1866.8052446434292;1897.4402551247415;1965.5380985468403;2049.600327936068;811.2212276214782;854.3848429131016;996.8405055191134;1009.9242556808268;1022.5091955861228;15542.611434714223;17891.73162402565;20578.38264852057;20983;21370.572588548453;904.3895370624933;928.8838895138108;989.8119357322086;1056.2025670618634;1088.4884104471043;10645.946770266099;10893;10908.127349811995;10918.344899305908;10937.671869843918;589276;601551;605500.8108389601;606050;609394;84078.89802754858;133911;136376;136439;139670.60329396778;1009.6062535611118;1033.0702367869128;1037.8806437259811;1061.1881910338598;1066.3814987307344;18031.819681803016;18276.08619569043;18315.908420458345;18403;18535.629287414446;50500.74749626228;50918.490815091376;51814.222786657476;53061.734691326295;56706.86586268334;80139;82474;83356.7496487541;84410;84965.57517212375;7208.639568021683;7539.396848252236;7649.923500765144;8045.275925166763;8322.958385208036;12141.57504487374;13049.412776424893;13053.738925221633;13412.597622071353;13422.261775602045;7163.964180179066;10739.239452532891;11048.613298534512;11623.59317423886;11649.534018639499;9208.756817829508;11190.94404527972;11355;11454.427278635869;11622.767544649229;11279.379634120201;11628.360440175871;11698.824517632072;11883.643490695293;11906.464209110783;8280.461769984931;8284.378671599774;8414.368922330972;8433.45182563156;8461.746147615582;8509.404341696021;11003.944980275048;11284.887151128383;11391.487383067617;11614.477348519506 12:28:40 INFO - PROCESS | 1976 | |3;mootools.html;1434.6700258940436;1588.8411158884112;1625.6667383186445;1699.498647898869;1708.3508266858591;7357.484976051678;8055.6374963126655;8815.51514666694;9039.32205084618;9183.540822958843;8788.34087443441;9032.187103160713;9033.864492032624;9045.592948317326;9050.954745226281;2043.1520918818671;2355.3169580821627;2398.9880050599722;2438.926832195037;2453.9631905521433;2453.9815976369873;2471.5551200783852;2476.5789815731277;2480.3675062858947;2481.3424442522746;2395.964060539093;2446.4617784087473;2451.0440928037992;2453.6564880916685;2460.458699086198;2030.6953956906434;2112.7359080114984;2364.385259832439;2430.4409985703387;2444.706635203769;2433.58629033063;2443.8044956403446;2466.666999955001;2468.790152837011;2469.09877894569;9000.504972226525;9073.54632268384;9084.8637270441;9091.454512729257;9107.043760405195;5390.164524498709;5567.8608034799045;5580.386157522669;5580.609357344987;5592.888142237132;2903.506403911319;3442.621311655716;3486.529318542002;3488.215151590897;3526.982365088184;881.5680316644862;1051.673981065872;1060.6499855047894;1061.347271428074;1065.0733861540432;12179.51281948721;12197.573084941985;12222.755544889053;12225.449854756469;12227.69430764229;4817.445993710698;4856.63575231852;4890.657653964259;4907.043126590352;4940.975295123501;10071.043250891154;10102.242331825142;10102.949485252673;10109.494525273707;10118;5504.119340905436;5504.4495550444635;5509.393966663663;5517.834464966058;5528.723563821793;4074.3684728867074;4508.323751437245;4660.697054691502;5017.698938063728;5034.949650503521;3633.800140992271;4034.1931613676793;4104.610062044101;4147.419361289361;4172.603602657743;1335.706144648176;1340.2829486224823;1341.1550723044616;1353.2759973414177;1364.3519328318969;895.552223888056;898.7169041751959;919.4667093085994;937.540605103508;953.3231405702029;5507.146392309199;5962.8968640801595;6019.247594050744;6035.30593981689;6079.574429789961;398.93484396660983;430.2663957951685;432;450.9143262780061;451.23515640988865;3897.785621790772;3900.5904380040256;3961.544422391404;4026.355783074694;4184.2468355696265;10996.780064398827;11093.389863557582;11100.333979961228;11242.887571124347;11305.660830175108;11190.216684831983;11192.44037798107;11196.216264861381;11202.38386888729;11204.327740335442;6874.2094659113845;8292.834143317099;8371.832563348698;8521.659133634708;8534.530600817016;5907.586468947133;5987.503124218945;6043.153958445909;6104.694765261808;6109.1141784440115;10384.792304154025;10399.896001040195;10459.372437653765;10465.581376744845;10487.318324308895;9834.557444915139;10288.176945844165;10420.635277765241;10438.63464778729;10557.9472102639;7070.434365250665;7289.8177545559665;7530.6611202497115;7711.537307761552;7872.330851877426;8503.404761666625;8626.82746345057;8686.261667758312;8695.478271303742;8732.56337183151;7523.022007139037;7670.578118203553;7708.190639982945;7825.804354891174;7854.842903142019;7503.66233519482;7532.887006694795;7555.962220188864;7705.1909549496495;7756.185600512091;6161.322254551906;7154.141503019463;7250.057492525938;7262.527935684164;7278.980942668137;4667.813287468463;4877.317175595491;4921.136275320563;4924.458309586014;4939.876503087452;4297.474396589166;4951.405831300123;4998.950010499994;5017.974910125426;5023.372078490189 12:28:40 INFO - PROCESS | 1976 | |4;prototype.html;311.5000424318968;319.331001551749;323.11144353029164;326.9640339562649;329.40377915972107;4140.565240649734;5019.799208031674;5267.23625074364;5370.167624018273;5436.891262174754;4795.568398844103;4949.752512374376;5123.871903202422;5364.356277246736;5387.245785590011;3079.8922037728685;3317.817520036397;3865.6327648873385;4094.4267802507675;4125.31932231183;3480.269143479872;4024.5371782245124;4049.198106842456;4064.9390259146135;4091.365838295069;3615.475756015376;4026.2752704513177;4045.766041357385;4072.552019277877;4077.5922407759135;3859.3824988001925;3887.6673995602696;4048.554658987509;4083.734557253799;4121.711480196387;2979.7616190704694;3523.594786599548;3542.3800834853796;3549.2014296783277;3556.4309710446464;5179.549726076704;5187.507186817246;5212.192110222922;5257.316548848664;5276.894462110775;4076.2866498362964;4445.288753799409;4657.5575320344515;4689.484156742756;4730.361401210827;5552.305961754781;5596.384397716248;5630.733085055871;5633.492985631272;5692.57305702074;3919.8824035278703;3923.568407475204;3936.1734035852505;3937.7243592948507;3941.2905676978135;5118.51563046716;5332.821035727852;5421.186821976696;5796.449337312991;5893.497158224711;3390.5083762854247;3404.472306792451;3548.7870727756417;3974.4435778990965;4007.6192761687594;3524.6299138590593;4047.7047344849366;4071.328230841937;4077.7970498702953;4120.743173332103;3790.5451345838123;4183.184279065614;4286.121345124242;4712.293156026555;4735.59747421473;5426.457354264542;5448.046591846411;5678.545716342683;5709.571782116275;5754.769809207585;1349.8920086393066;1383.3083458270864;1386.2167875150508;1386.473140206715;1391.839938407096;770.2323168328699;831.1024093978489;851.2126283188027;856.6165642487338;873.5850471026212;5051.459304912037;5208.8958220835375;5240.554552862973;5329.9467005330225;5385.845661735337;5582.218489411485;5785.392533783976;5816.214811000461;5851.986227093051;5881.294244690579;4957.553820156203;5257.710825904535;5281.419043905167;5316.58095566524;5321.441248668912;2690.4753573053067;2863.642044744407;3034.150437877398;3062.372213696187;3075.6616772155044;5477.260569823103;5844.8538786529825;6064.3935606439;6101.60339577926;6126.866529691932;5359.812406565751;5369.785208591613;5422.783088676409;6085.96957015221;6113.908291375633;6064.876588556896;6113.633182009094;6134.263888333339;6136.386361363828;6139.140520327069;4660.86017419478;5083.389993200692;5853.619514731528;5918.981319526986;5925.229720136354;5741.684207368635;5761.251037365116;5778.971105144447;5795.420457954171;5819.592628515963;5849.017001329437;5951.869144862462;5969.910531314179;6019.879602408015;6158.076288556752;3954.426608141861;3955.169414423003;3956.4263181839046;3962.643362097425;3974.642282194616;4752.239641657318;5206.958608278435;5677.858053548694;5905.202797622266;5917.6033706349;4471.7987690554655;4888.853334399973;5040.253062081101;5705.828825135252;5771.365149833431;5236.659617124875;5505.889882202414;5717.799877004285;5723.284589426322;5738.196652468741;4745.549172828576;4756.809727610996;5400.621956463167;5486.478784515464;5640.689762063126;5189.6107791915;5223.425423203368;5745.621050947659;5774.39368866263;5787;5585;5630.296212973379;5634.168895109024;5648.378678345461;5682 12:28:40 INFO - PROCESS | 1976 | |5;yui.html;603.7735849056604;692.6294432478625;696.0846486869763;704.6124631452701;705.1467724053895;13485.39315730792;13915.956303277257;14086;14118.576442706735;14140.15159090454;12775.872241277584;12778.977681785458;12820.61538153857;12843.871561284384;12847.357632118403;753.6005916864057;907.7594437474074;914.862770584411;926.578406824893;926.7868390270241;897.5153417154729;903.7062954539768;905.2169873059789;905.7192270396198;909.958097977815;955.6416343871049;956;956.8373376525973;957.3777044920788;961.418341903149;952.7808604021079;953.1897886796237;953.5661274120258;953.918916892065;954.8376775948071;708.7413094220573;708.7413094220624;709.0463326825411;710.0414440505276;710.6091649592703;13367.131136476195;13392.799108013387;13426.664333391744;13433.992450566247;13453.932730336384;11865.703357416047;11941;12029;12100.152989290753;12163.17552367143;7604.619769011527;7730.574818384986;9368.126374725081;9398.248140148771;9699.90300096997;3419.9316013679586;3420.8973730788116;3422.2984288220855;3473.600535938349;3676.9080772980624;76674.61662691706;77338.22661773367;78236.21763782346;78331.04172395679;79261.60369198232;9057.18485336323;9095.363324567217;9198.770030749285;9230.676926307548;9656.9034309656;20147.899260503604;20167;20169.899150504152;20173.49566260826;20177.293794717116;6947.444204463631;6958.652067396712;7081.044059052065;7140.678669459887;7172.928270717331;3909.882703518899;3981.820818063194;3990.4413382126527;3994.520657521116;3994.5406278277787;3342.131045928076;3445.0698311455767;3447.7069449097107;3448.5861696596066;3456.55064841569;615.9106929495288;635.7266375458505;639.4947991086989;640.942315191635;655.9275584419413;488.16280079663545;538.4669177514311;552;560.0982418306523;564.4693987651594;7474.850502990019;7608.972788673569;7624.847503049908;7639.197884222077;7745.186755390603;498.13822087788327;498.76059491443584;509.4497942222391;511.3046257090354;511.59583928696657;3580.588232353313;3590;3626.5648122224975;3630.6006339302658;3789.981050094787;71570;86962.69555956665;88192.55903720441;89833.55083224672;92684;63242.36757632457;73233.26766732264;73254.6337268321;74628.62685686645;85062.57468712617;70644.58710825755;71197.64401177962;71453.28546714467;71462;71990.8807751347;7672.271134242238;8004.079530853794;8039.638216280165;8161.224683655043;8186;43449.34825977676;43882;44182.77908610436;44281.557184427744;44653.55346446494;57303.14045289408;58944.694977452236;61965;62038.9674671145;63810.68094659497;10722.463876806285;10762.515686793957;10764.515596798006;10889.713160236122;11220.719482013015;11480.024197943269;11483.770324593628;11559.248648837798;11565.363904985306;11964.58123965657;10909.34543927367;10917.290376125526;11001.449927503432;11185.72035699114;11407.25852819564;9746.415215087118;9841.901580984384;9847;10087.193024557871;10910.454477275945;9155.902912428262;9463.101005404476;9562.043795620382;9566.66516671913;9584.089511496397;8151.959240203998;8260.504369737835;8449.028361738234;8509.914900850912;8533.104024077631;8061.758147255592;8326.375521836007;8444.324454043784;8457.746267611981;8514.829703406022 12:28:40 INFO - PROCESS | 1976 | __end_tp_report 12:28:40 INFO - PROCESS | 1976 | __start_cc_report 12:28:40 INFO - PROCESS | 1976 | _x_x_mozilla_cycle_collect,1094 12:28:40 INFO - PROCESS | 1976 | __end_cc_report 12:28:40 INFO - PROCESS | 1976 | __startTimestamp1463426920258__endTimestamp 12:28:40 INFO - PROCESS | 1976 | 12:28:40 INFO - PROCESS | 1976 | ------- Summary: start ------- 12:28:40 INFO - PROCESS | 1976 | Number of tests: 6 12:28:40 INFO - PROCESS | 1976 | 12:28:40 INFO - PROCESS | 1976 | [#0] dojo.html Cycles:165 Average:5650.96 Median:5930.94 stddev:649.87 (11.0%) stddev-sans-first:626.90 12:28:40 INFO - PROCESS | 1976 | Values: 3377.1 3377.6 3403.3 3414.4 3456.2 5569.3 5572.9 5606.3 5657.3 5657.6 5610.1 5664.0 5679.0 5681.9 5701.2 5054.3 5094.3 5121.5 5122.9 5181.2 5152.0 5176.9 5184.5 5185.1 5187.2 5071.7 5087.7 5109.5 5116.6 5181.5 5154.7 5197.0 5202.5 5203.3 5249.3 5007.8 5018.6 5049.3 5113.1 5119.5 4638.9 5531.3 5536.1 5558.4 5586.1 6109.1 6128.5 6151.0 6152.0 6167.2 6090.3 6123.1 6169.2 6216.5 6252.0 4793.9 5723.5 5775.5 5811.5 5812.5 6206.5 6220.2 6225.2 6238.0 6250.8 4934.4 6029.8 6088.8 6109.3 6122.5 5196.9 6198.8 6200.8 6231.8 6234.6 6125.5 6154.4 6167.1 6186.9 6193.1 6054.1 6106.6 6115.3 6119.9 6143.1 4767.0 4779.8 4786.6 4810.1 4814.9 4365.8 4387.5 4400.4 4405.7 4406.2 4769.4 5575.2 5654.1 5667.4 5699.4 6205.5 6222.6 6238.0 6254.0 6261.0 5552.0 5640.5 5666.6 5674.7 5685.5 5246.2 5280.8 5319.4 5328.3 5337.7 6215.5 6219.8 6228.1 6237.3 6254.2 6121.1 6228.6 6244.3 6278.8 6293.2 5648.3 5908.6 6160.1 6183.6 6250.5 6110.6 6121.3 6133.3 6135.5 6163.5 4722.7 6071.4 6243.6 6247.4 6255.9 6003.4 6043.7 6046.5 6068.9 6080.2 6051.5 6055.4 6113.2 6113.3 6114.9 5820.2 5895.2 5952.6 5965.2 5980.0 6046.2 6109.3 6116.6 6119.7 6121.6 5930.9 5947.1 5947.4 5954.2 5980.8 12:28:40 INFO - PROCESS | 1976 | 12:28:40 INFO - PROCESS | 1976 | [#1] ext.html Cycles:180 Average:75382.47 Median:36482.23 stddev:176356.02 (483.4%) stddev-sans-first:176761.13 12:28:40 INFO - PROCESS | 1976 | Values: 502.6 514.6 536.7 543.8 549.7 31154.2 31321.7 32537.9 32602.8 43550.8 43009.8 45554.5 45601.0 45656.5 45776.3 9755.9 9955.2 9956.2 9960.0 9965.8 8079.3 8791.6 8951.6 8965.0 9701.6 8848.8 8908.1 11741.7 11985.0 11993.0 11864.4 11948.8 11976.3 11990.0 12011.9 8697.7 11587.6 11694.5 11708.9 11725.9 41039.8 41710.0 41790.2 43329.6 43433.3 3261.8 3429.3 3481.7 3530.3 3672.9 28844.7 28971.9 29316.9 29824.6 30122.4 2712.7 2723.8 2732.3 2762.3 2816.4 528438.0 530652.0 531400.8 531697.0 532769.0 35859.5 36731.5 36798.6 63705.0 63781.7 37476.0 37515.6 37633.0 37650.8 37695.2 12864.9 15277.0 15369.8 15417.5 15437.7 12919.4 13011.6 13035.3 13040.2 13045.4 7747.8 7916.6 8215.8 8243.7 8294.8 3332.5 3393.0 3401.6 3429.0 3449.2 953.0 979.3 999.4 1044.0 1118.9 29323.7 29448.1 29830.7 30037.0 30071.2 1121.4 1122.0 1179.6 1207.2 1230.0 15271.4 15283.3 15296.2 15644.2 15668.8 701330.0 706570.0 713143.0 713627.0 718629.4 54721.0 55798.7 62318.0 63314.0 63641.7 1337.3 1479.2 1500.0 1504.6 1509.0 29764.6 30153.8 30657.8 30879.0 31166.5 53850.0 53857.0 54337.2 54340.2 54418.0 626035.0 651129.0 678943.0 679008.0 881447.0 26619.7 26859.9 26900.9 26909.2 26961.6 45756.5 46215.8 46642.8 46679.5 47685.8 36345.3 37464.8 37893.1 38519.0 38550.6 33114.5 36430.6 36533.8 37011.0 38619.8 36848.1 37166.6 37658.8 37802.2 37865.8 26053.6 31778.8 33024.5 33182.3 33392.8 21549.4 24222.9 24620.5 24899.9 25493.9 12:28:40 INFO - PROCESS | 1976 | 12:28:40 INFO - PROCESS | 1976 | [#2] jquery.html Cycles:180 Average:34827.54 Median:11767.77 stddev:100354.09 (852.8%) stddev-sans-first:100602.30 12:28:40 INFO - PROCESS | 1976 | Values: 393.6 397.6 401.2 408.4 428.8 17269.2 20079.5 20254.6 21229.2 21411.3 20554.2 20853.0 21394.3 21756.5 21881.2 5879.9 6112.4 6167.2 6167.3 6174.3 5867.7 6067.1 6127.0 6129.5 6218.9 6251.6 6252.6 6265.4 6270.7 6287.2 6234.9 6308.7 6978.4 6980.2 6996.5 5301.0 6091.8 6103.3 6124.7 6127.2 14097.9 14115.4 19290.7 25554.6 26066.0 1922.9 2056.8 2061.1 2141.3 2226.7 9936.0 10002.1 10416.3 10611.3 14495.7 1756.9 1768.3 1769.0 1769.4 1774.1 85467.6 107598.0 111194.0 113529.4 116407.8 11433.3 11759.5 11788.0 11798.2 11949.7 14760.0 15209.8 15354.6 15416.0 15416.8 7711.8 7920.3 8349.6 8812.4 9016.8 8620.2 8688.0 8927.2 9006.2 9018.0 4636.7 4648.6 4735.7 4735.9 4824.8 1737.3 1866.8 1897.4 1965.5 2049.6 811.2 854.4 996.8 1009.9 1022.5 15542.6 17891.7 20578.4 20983.0 21370.6 904.4 928.9 989.8 1056.2 1088.5 10645.9 10893.0 10908.1 10918.3 10937.7 589276.0 601551.0 605500.8 606050.0 609394.0 84078.9 133911.0 136376.0 136439.0 139670.6 1009.6 1033.1 1037.9 1061.2 1066.4 18031.8 18276.1 18315.9 18403.0 18535.6 50500.7 50918.5 51814.2 53061.7 56706.9 80139.0 82474.0 83356.7 84410.0 84965.6 7208.6 7539.4 7649.9 8045.3 8323.0 12141.6 13049.4 13053.7 13412.6 13422.3 7164.0 10739.2 11048.6 11623.6 11649.5 9208.8 11190.9 11355.0 11454.4 11622.8 11279.4 11628.4 11698.8 11883.6 11906.5 8280.5 8284.4 8414.4 8433.5 8461.7 8509.4 11003.9 11284.9 11391.5 11614.5 12:28:40 INFO - PROCESS | 1976 | 12:28:40 INFO - PROCESS | 1976 | [#3] mootools.html Cycles:180 Average:5820.64 Median:2555.97 stddev:3346.69 (130.9%) stddev-sans-first:3339.85 12:28:40 INFO - PROCESS | 1976 | Values: 1434.7 1588.8 1625.7 1699.5 1708.4 7357.5 8055.6 8815.5 9039.3 9183.5 8788.3 9032.2 9033.9 9045.6 9051.0 2043.2 2355.3 2399.0 2438.9 2454.0 2454.0 2471.6 2476.6 2480.4 2481.3 2396.0 2446.5 2451.0 2453.7 2460.5 2030.7 2112.7 2364.4 2430.4 2444.7 2433.6 2443.8 2466.7 2468.8 2469.1 9000.5 9073.5 9084.9 9091.5 9107.0 5390.2 5567.9 5580.4 5580.6 5592.9 2903.5 3442.6 3486.5 3488.2 3527.0 881.6 1051.7 1060.6 1061.3 1065.1 12179.5 12197.6 12222.8 12225.4 12227.7 4817.4 4856.6 4890.7 4907.0 4941.0 10071.0 10102.2 10102.9 10109.5 10118.0 5504.1 5504.4 5509.4 5517.8 5528.7 4074.4 4508.3 4660.7 5017.7 5034.9 3633.8 4034.2 4104.6 4147.4 4172.6 1335.7 1340.3 1341.2 1353.3 1364.4 895.6 898.7 919.5 937.5 953.3 5507.1 5962.9 6019.2 6035.3 6079.6 398.9 430.3 432.0 450.9 451.2 3897.8 3900.6 3961.5 4026.4 4184.2 10996.8 11093.4 11100.3 11242.9 11305.7 11190.2 11192.4 11196.2 11202.4 11204.3 6874.2 8292.8 8371.8 8521.7 8534.5 5907.6 5987.5 6043.2 6104.7 6109.1 10384.8 10399.9 10459.4 10465.6 10487.3 9834.6 10288.2 10420.6 10438.6 10557.9 7070.4 7289.8 7530.7 7711.5 7872.3 8503.4 8626.8 8686.3 8695.5 8732.6 7523.0 7670.6 7708.2 7825.8 7854.8 7503.7 7532.9 7556.0 7705.2 7756.2 6161.3 7154.1 7250.1 7262.5 7279.0 4667.8 4877.3 4921.1 4924.5 4939.9 4297.5 4951.4 4999.0 5018.0 5023.4 12:28:40 INFO - PROCESS | 1976 | 12:28:40 INFO - PROCESS | 1976 | [#4] prototype.html Cycles:180 Average:4580.52 Median:5210.54 stddev:1428.85 (27.4%) stddev-sans-first:1396.47 12:28:40 INFO - PROCESS | 1976 | Values: 311.5 319.3 323.1 327.0 329.4 4140.6 5019.8 5267.2 5370.2 5436.9 4795.6 4949.8 5123.9 5364.4 5387.2 3079.9 3317.8 3865.6 4094.4 4125.3 3480.3 4024.5 4049.2 4064.9 4091.4 3615.5 4026.3 4045.8 4072.6 4077.6 3859.4 3887.7 4048.6 4083.7 4121.7 2979.8 3523.6 3542.4 3549.2 3556.4 5179.5 5187.5 5212.2 5257.3 5276.9 4076.3 4445.3 4657.6 4689.5 4730.4 5552.3 5596.4 5630.7 5633.5 5692.6 3919.9 3923.6 3936.2 3937.7 3941.3 5118.5 5332.8 5421.2 5796.4 5893.5 3390.5 3404.5 3548.8 3974.4 4007.6 3524.6 4047.7 4071.3 4077.8 4120.7 3790.5 4183.2 4286.1 4712.3 4735.6 5426.5 5448.0 5678.5 5709.6 5754.8 1349.9 1383.3 1386.2 1386.5 1391.8 770.2 831.1 851.2 856.6 873.6 5051.5 5208.9 5240.6 5329.9 5385.8 5582.2 5785.4 5816.2 5852.0 5881.3 4957.6 5257.7 5281.4 5316.6 5321.4 2690.5 2863.6 3034.2 3062.4 3075.7 5477.3 5844.9 6064.4 6101.6 6126.9 5359.8 5369.8 5422.8 6086.0 6113.9 6064.9 6113.6 6134.3 6136.4 6139.1 4660.9 5083.4 5853.6 5919.0 5925.2 5741.7 5761.3 5779.0 5795.4 5819.6 5849.0 5951.9 5969.9 6019.9 6158.1 3954.4 3955.2 3956.4 3962.6 3974.6 4752.2 5207.0 5677.9 5905.2 5917.6 4471.8 4888.9 5040.3 5705.8 5771.4 5236.7 5505.9 5717.8 5723.3 5738.2 4745.5 4756.8 5400.6 5486.5 5640.7 5189.6 5223.4 5745.6 5774.4 5787.0 5585.0 5630.3 5634.2 5648.4 5682.0 12:28:40 INFO - PROCESS | 1976 | 12:28:40 INFO - PROCESS | 1976 | [#5] yui.html Cycles:180 Average:17171.74 Median:6953.05 stddev:24406.95 (351.0%) stddev-sans-first:24443.71 12:28:40 INFO - PROCESS | 1976 | Values: 603.8 692.6 696.1 704.6 705.1 13485.4 13916.0 14086.0 14118.6 14140.2 12775.9 12779.0 12820.6 12843.9 12847.4 753.6 907.8 914.9 926.6 926.8 897.5 903.7 905.2 905.7 910.0 955.6 956.0 956.8 957.4 961.4 952.8 953.2 953.6 953.9 954.8 708.7 708.7 709.0 710.0 710.6 13367.1 13392.8 13426.7 13434.0 13453.9 11865.7 11941.0 12029.0 12100.2 12163.2 7604.6 7730.6 9368.1 9398.2 9699.9 3419.9 3420.9 3422.3 3473.6 3676.9 76674.6 77338.2 78236.2 78331.0 79261.6 9057.2 9095.4 9198.8 9230.7 9656.9 20147.9 20167.0 20169.9 20173.5 20177.3 6947.4 6958.7 7081.0 7140.7 7172.9 3909.9 3981.8 3990.4 3994.5 3994.5 3342.1 3445.1 3447.7 3448.6 3456.6 615.9 635.7 639.5 640.9 655.9 488.2 538.5 552.0 560.1 564.5 7474.9 7609.0 7624.8 7639.2 7745.2 498.1 498.8 509.4 511.3 511.6 3580.6 3590.0 3626.6 3630.6 3790.0 71570.0 86962.7 88192.6 89833.6 92684.0 63242.4 73233.3 73254.6 74628.6 85062.6 70644.6 71197.6 71453.3 71462.0 71990.9 7672.3 8004.1 8039.6 8161.2 8186.0 43449.3 43882.0 44182.8 44281.6 44653.6 57303.1 58944.7 61965.0 62039.0 63810.7 10722.5 10762.5 10764.5 10889.7 11220.7 11480.0 11483.8 11559.2 11565.4 11964.6 10909.3 10917.3 11001.4 11185.7 11407.3 9746.4 9841.9 9847.0 10087.2 10910.5 9155.9 9463.1 9562.0 9566.7 9584.1 8152.0 8260.5 8449.0 8509.9 8533.1 8061.8 8326.4 8444.3 8457.7 8514.8 12:28:40 INFO - PROCESS | 1976 | -------- Summary: end -------- 12:28:40 INFO - PROCESS | 1976 | 12:28:40 INFO - TEST-INFO | 1976: exit 0 12:28:40 INFO - TEST-OK | dromaeo_css | took 1116261ms 12:28:40 INFO - TEST-START | kraken 12:28:40 INFO - operating with platform_type : mac_ 12:28:40 INFO - Initialising browser for kraken test... 12:28:40 INFO - TEST-INFO | started process 1981 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmp6y7j7L/profile http://localhost:49527/getInfo.html) 12:28:43 INFO - PROCESS | 1981 | __metrics Screen width/height:1600/1200 12:28:43 INFO - PROCESS | 1981 | colorDepth:24 12:28:43 INFO - PROCESS | 1981 | Browser inner width/height: 1024/689 12:28:43 INFO - PROCESS | 1981 | __metrics 12:28:43 INFO - PROCESS | 1981 | 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:28:43 ERROR - PROCESS | 1981 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:28:44 INFO - TEST-INFO | 1981: exit 0 12:28:44 INFO - Browser initialized. 12:28:44 INFO - Running cycle 1/1 for kraken test... 12:28:44 INFO - TEST-INFO | started process 1983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmp6y7j7L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:56 INFO - PROCESS | 1983 | RSS: Main: 239198208 12:28:56 INFO - PROCESS | 1983 | 12:28:58 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49527/tests/kraken/driver.html?testName=audio-beat-detection) 12:28:59 INFO - PROCESS | 1983 | RSS: Main: 287133696 12:28:59 INFO - PROCESS | 1983 | 12:29:02 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49527/tests/kraken/driver.html?testName=audio-dft) 12:29:03 INFO - PROCESS | 1983 | RSS: Main: 296296448 12:29:03 INFO - PROCESS | 1983 | 12:29:06 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49527/tests/kraken/driver.html?testName=audio-fft) 12:29:07 INFO - PROCESS | 1983 | RSS: Main: 384155648 12:29:07 INFO - PROCESS | 1983 | 12:29:09 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49527/tests/kraken/driver.html?testName=audio-oscillator) 12:29:10 INFO - PROCESS | 1983 | RSS: Main: 283480064 12:29:10 INFO - PROCESS | 1983 | 12:29:12 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49527/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:29:12 INFO - PROCESS | 1983 | RSS: Main: 287195136 12:29:12 INFO - PROCESS | 1983 | 12:29:16 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49527/tests/kraken/driver.html?testName=imaging-darkroom) 12:29:16 INFO - PROCESS | 1983 | RSS: Main: 324321280 12:29:16 INFO - PROCESS | 1983 | 12:29:19 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49527/tests/kraken/driver.html?testName=imaging-desaturate) 12:29:20 INFO - PROCESS | 1983 | RSS: Main: 328462336 12:29:20 INFO - PROCESS | 1983 | 12:29:23 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49527/tests/kraken/driver.html?testName=json-parse-financial) 12:29:24 INFO - PROCESS | 1983 | RSS: Main: 326848512 12:29:24 INFO - PROCESS | 1983 | 12:29:25 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49527/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:29:26 INFO - PROCESS | 1983 | RSS: Main: 316403712 12:29:26 INFO - PROCESS | 1983 | 12:29:28 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49527/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:29:28 INFO - PROCESS | 1983 | RSS: Main: 323645440 12:29:28 INFO - PROCESS | 1983 | 12:29:31 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49527/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:29:31 INFO - PROCESS | 1983 | RSS: Main: 324923392 12:29:31 INFO - PROCESS | 1983 | 12:29:34 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:29:35 INFO - PROCESS | 1983 | RSS: Main: 329830400 12:29:35 INFO - PROCESS | 1983 | 12:29:38 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:39 INFO - PROCESS | 1983 | RSS: Main: 327254016 12:29:39 INFO - PROCESS | 1983 | 12:29:40 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49527/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49527/tests/kraken/driver.html?testName=ai-astar) 12:29:41 INFO - PROCESS | 1983 | RSS: Main: 373907456 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | __start_tp_report 12:29:41 INFO - PROCESS | 1983 | _x_x_mozilla_page_load 12:29:41 INFO - PROCESS | 1983 | _x_x_mozilla_page_load_details 12:29:41 INFO - PROCESS | 1983 | |i|pagename|runs| 12:29:41 INFO - PROCESS | 1983 | |0;ai-astar;112;105;111;111;112;111;115;160;155;114 12:29:41 INFO - PROCESS | 1983 | |1;audio-beat-detection;154;120;141;194;119;139;136;116;172;138 12:29:41 INFO - PROCESS | 1983 | |2;audio-dft;186;185;185;183;184;182;184;182;180;195 12:29:41 INFO - PROCESS | 1983 | |3;audio-fft;77;74;57;126;56;62;56;60;110;55 12:29:41 INFO - PROCESS | 1983 | |4;audio-oscillator;93;101;132;78;79;76;101;79;84;101 12:29:41 INFO - PROCESS | 1983 | |5;imaging-gaussian-blur;86;86;132;70;129;82;121;127;104;83 12:29:41 INFO - PROCESS | 1983 | |6;imaging-darkroom;93;92;93;94;92;92;93;94;92;91 12:29:41 INFO - PROCESS | 1983 | |7;imaging-desaturate;86;84;85;86;86;85;85;85;85;84 12:29:41 INFO - PROCESS | 1983 | |8;json-parse-financial;56;59;58;56;55;55;57;115;56;54 12:29:41 INFO - PROCESS | 1983 | |9;json-stringify-tinderbox;63;65;74;73;69;67;68;68;68;68 12:29:41 INFO - PROCESS | 1983 | |10;stanford-crypto-aes;82;85;86;86;92;93;106;105;85;85 12:29:41 INFO - PROCESS | 1983 | |11;stanford-crypto-ccm;136;137;137;137;140;139;148;130;225;145 12:29:41 INFO - PROCESS | 1983 | |12;stanford-crypto-pbkdf2;163;162;162;160;164;162;166;161;161;161 12:29:41 INFO - PROCESS | 1983 | |13;stanford-crypto-sha256-iterative;66;67;68;69;80;79;81;83;79;77 12:29:41 INFO - PROCESS | 1983 | __end_tp_report 12:29:41 INFO - PROCESS | 1983 | __start_cc_report 12:29:41 INFO - PROCESS | 1983 | _x_x_mozilla_cycle_collect,2248 12:29:41 INFO - PROCESS | 1983 | __end_cc_report 12:29:41 INFO - PROCESS | 1983 | __startTimestamp1463426981189__endTimestamp 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | ------- Summary: start ------- 12:29:41 INFO - PROCESS | 1983 | Number of tests: 14 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#0] ai-astar Cycles:10 Average:120.60 Median:113.00 stddev:19.66 (17.4%) stddev-sans-first:20.60 12:29:41 INFO - PROCESS | 1983 | Values: 112.0 105.0 111.0 111.0 112.0 111.0 115.0 160.0 155.0 114.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:140.00 stddev:24.70 (17.6%) stddev-sans-first:25.87 12:29:41 INFO - PROCESS | 1983 | Values: 154.0 120.0 141.0 194.0 119.0 139.0 136.0 116.0 172.0 138.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#2] audio-dft Cycles:10 Average:184.60 Median:184.50 stddev:4.06 (2.2%) stddev-sans-first:4.28 12:29:41 INFO - PROCESS | 1983 | Values: 186.0 185.0 185.0 183.0 184.0 182.0 184.0 182.0 180.0 195.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#3] audio-fft Cycles:10 Average:73.30 Median:58.50 stddev:25.04 (42.8%) stddev-sans-first:26.52 12:29:41 INFO - PROCESS | 1983 | Values: 77.0 74.0 57.0 126.0 56.0 62.0 56.0 60.0 110.0 55.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:78.50 stddev:17.31 (22.0%) stddev-sans-first:18.36 12:29:41 INFO - PROCESS | 1983 | Values: 93.0 101.0 132.0 78.0 79.0 76.0 101.0 79.0 84.0 101.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#5] imaging-gaussian-blur Cycles:10 Average:102.00 Median:76.00 stddev:23.37 (30.8%) stddev-sans-first:24.06 12:29:41 INFO - PROCESS | 1983 | Values: 86.0 86.0 132.0 70.0 129.0 82.0 121.0 127.0 104.0 83.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 12:29:41 INFO - PROCESS | 1983 | Values: 93.0 92.0 93.0 94.0 92.0 92.0 93.0 94.0 92.0 91.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:0.74 (0.9%) stddev-sans-first:0.71 12:29:41 INFO - PROCESS | 1983 | Values: 86.0 84.0 85.0 86.0 86.0 85.0 85.0 85.0 85.0 84.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:56.00 stddev:18.65 (33.3%) stddev-sans-first:19.65 12:29:41 INFO - PROCESS | 1983 | Values: 56.0 59.0 58.0 56.0 55.0 55.0 57.0 115.0 56.0 54.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:3.27 (4.8%) stddev-sans-first:2.85 12:29:41 INFO - PROCESS | 1983 | Values: 63.0 65.0 74.0 73.0 69.0 67.0 68.0 68.0 68.0 68.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:85.50 stddev:8.58 (10.0%) stddev-sans-first:8.53 12:29:41 INFO - PROCESS | 1983 | Values: 82.0 85.0 86.0 86.0 92.0 93.0 106.0 105.0 85.0 85.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:139.50 stddev:27.71 (19.9%) stddev-sans-first:29.08 12:29:41 INFO - PROCESS | 1983 | Values: 136.0 137.0 137.0 137.0 140.0 139.0 148.0 130.0 225.0 145.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:162.00 stddev:1.75 (1.1%) stddev-sans-first:1.83 12:29:41 INFO - PROCESS | 1983 | Values: 163.0 162.0 162.0 160.0 164.0 162.0 166.0 161.0 161.0 161.0 12:29:41 INFO - PROCESS | 1983 | 12:29:41 INFO - PROCESS | 1983 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:79.00 stddev:6.59 (8.3%) stddev-sans-first:6.15 12:29:41 INFO - PROCESS | 1983 | Values: 66.0 67.0 68.0 69.0 80.0 79.0 81.0 83.0 79.0 77.0 12:29:41 INFO - PROCESS | 1983 | -------- Summary: end -------- 12:29:41 INFO - PROCESS | 1983 | 12:29:42 INFO - TEST-INFO | 1983: exit 0 12:29:42 INFO - TEST-OK | kraken | took 61075ms 12:29:42 INFO - SUITE-END | took 1177s 12:29:42 INFO - Completed test suite (00:19:37) 12:29:42 INFO - javascript benchmark 12:29:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3377.071305391017, 3377.5609170807793, 3403.3193361327735, 3414.3683418567584, 3456.239627281997, 5569.275994120763, 5572.944270557293, 5606.299212598426, 5657.2645556077705, 5657.575681823864, 5610.074337734269, 5664.0, 5678.971605141979, 5681.886362272752, 5701.2018317435595, 5054.3429354184, 5094.337736094302, 5121.462246464123, 5122.897542049157, 5181.222816577506, 5151.995360904625, 5176.896462070757, 5184.455632158626, 5185.092608793465, 5187.19598462239, 5071.746412679351, 5087.720175390352, 5109.540141387274, 5116.565091967187, 5181.53366197044, 5154.664946778447, 5196.9740151299, 5202.479752024805, 5203.297554830087, 5249.265102885599, 5007.824726134568, 5018.623603229736, 5049.267856160836, 5113.054084994288, 5119.488051194888, 4638.8608341749805, 5531.3362396512675, 5536.086545719952, 5558.416366281563, 5586.078297080977, 6109.14471973924, 6128.479079278267, 6151.015837465984, 6152.0, 6167.229096362955, 6090.299615544225, 6123.081537769326, 6169.167162433059, 6216.471599914013, 6252.0, 4793.9280910786365, 5723.484886360247, 5775.451332123442, 5811.506021988093, 5812.534997200214, 6206.534509911775, 6220.191375121205, 6225.1907252057845, 6237.968810156011, 6250.781222657276, 4934.383202099738, 6029.8191054268445, 6088.756449742049, 6109.327973922865, 6122.479589234876, 5196.8960620787375, 6198.783042593487, 6200.7519699211525, 6231.844203894848, 6234.625922444668, 6125.540584456184, 6154.353792851775, 6167.136600875881, 6186.938130618636, 6193.1329613854095, 6054.061620448838, 6106.582013105394, 6115.327313995457, 6119.938800611937, 6143.139960405547, 4766.952330476651, 4779.761011949388, 4786.617070634341, 4810.13417584838, 4814.8925747078365, 4365.80353884076, 4387.473503179639, 4400.405945197422, 4405.713628614129, 4406.228909940747, 4769.403824521934, 5575.219469274305, 5654.06707893193, 5667.433256674382, 5699.373068962411, 6205.503559715212, 6222.595531290543, 6238.0, 6254.0, 6261.029540421242, 5552.0, 5640.5487560995025, 5666.631668941588, 5674.687892165888, 5685.4883060523925, 5246.160614301693, 5280.762365693553, 5319.361676598755, 5328.333958255218, 5337.733113344318, 6215.502759779117, 6219.844503887439, 6228.128062071313, 6237.251529816451, 6254.155688982111, 6121.143039974496, 6228.56400051992, 6244.250689917239, 6278.780242691484, 6293.181886354744, 5648.293963254593, 5908.645481271137, 6160.13758073861, 6183.628982261079, 6250.468710159687, 6110.572259941761, 6121.265448146251, 6133.325334213144, 6135.509159267337, 6163.476104531165, 4722.716637001791, 6071.392860713893, 6243.625382477066, 6247.406496382836, 6255.906161407674, 6003.369646187262, 6043.667598282137, 6046.546509011754, 6068.939310606837, 6080.2399700037495, 6051.485623722033, 6055.424734650202, 6113.174721412553, 6113.29697084841, 6114.908276375769, 5820.185174075717, 5895.233619629421, 5952.5833191676165, 5965.194698715618, 5979.970100149471, 6046.183765191819, 6109.34393638171, 6116.640127388606, 6119.722872950242, 6121.574916084701, 5930.940690593211, 5947.137665038632, 5947.405259474018, 5954.225950626391, 5980.760769569342], "value": 5611.4002849385015, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.62805523912306, 514.6397521734784, 536.6511767351221, 543.7607452720803, 549.6619578958941, 31154.2211444714, 31321.68678313216, 32537.861174858885, 32602.836985815036, 43550.782246088806, 43009.78495107528, 45554.54445455544, 45601.0, 45656.54343456565, 45776.313355299615, 9755.853662195055, 9955.153811925995, 9956.153726933184, 9960.0, 9965.75085622858, 8079.272865442109, 8791.64833406663, 8951.597178127, 8964.955175224115, 9701.563429645648, 8848.823023539526, 8908.109189081104, 11741.706457338549, 11985.041196704242, 11993.040556755439, 11864.406779661025, 11948.820767688492, 11976.341301228429, 11990.0, 12011.93994030033, 8697.69558065471, 11587.594434194763, 11694.532218711327, 11708.94619484242, 11725.94137029318, 41039.76880693584, 41710.0, 41790.1641967162, 43329.566704332865, 43433.34849977253, 3261.8369081546066, 3429.262708517656, 3481.721462283011, 3530.3292374448774, 3672.926541469156, 28844.71155288441, 28971.855140724372, 29316.853415733, 29824.552631710543, 30122.397552048835, 2712.742289482496, 2723.8093333466663, 2732.3442373830335, 2762.2541913683194, 2816.380396312808, 528438.0, 530652.0, 531400.7729574871, 531697.0, 532769.0, 35859.4621080684, 36731.510506501836, 36798.63201368006, 63705.044424333675, 63781.68109159517, 37476.0, 37515.62484375176, 37633.0, 37650.811745941646, 37695.24609507794, 12864.871351286554, 15277.0, 15369.769453458208, 15417.460388886335, 15437.691246175014, 12919.354032298348, 13011.609651710463, 13035.283059431633, 13040.152390094614, 13045.412956416794, 7747.767566973, 7916.643751031217, 8215.794605134919, 8243.711470098518, 8294.751157465285, 3332.483465062919, 3393.0499460150704, 3401.6428275030767, 3429.0, 3449.2411669432686, 952.9809403811885, 979.329159525713, 999.4203362049993, 1044.0081922173965, 1118.927269727465, 29323.706762932525, 29448.11655650334, 29830.70169298279, 30037.0, 30071.248218794266, 1121.3720316622716, 1121.9775604487866, 1179.6402097360387, 1207.2334067866793, 1230.034422977958, 15271.38914443432, 15283.312250948735, 15296.235188240766, 15644.217789110726, 15668.843311566967, 701330.0, 706570.0, 713143.0, 713627.0, 718629.4068529728, 54721.0, 55798.721006394706, 62318.0, 63314.0, 63641.681791590745, 1337.338017681254, 1479.179055624139, 1500.0324790510278, 1504.593759684857, 1509.0, 29764.553531697475, 30153.849230753705, 30657.846710767197, 30879.0, 31166.532502012033, 53850.0, 53857.0, 54337.18494222511, 54340.184897227366, 54418.0, 626035.0, 651129.0, 678943.0, 679008.0, 881447.0, 26619.733802661725, 26859.865700671373, 26900.865495672395, 26909.192724218305, 26961.595576066768, 45756.542434575225, 46215.76892115518, 46642.766786165856, 46679.53320466888, 47685.76157119192, 36345.27309453849, 37464.812675936446, 37893.05267368338, 38519.0, 38550.6144938547, 33114.50328245127, 36430.63569364379, 36533.817330913174, 37011.0, 38619.8414047579, 36848.07879803026, 37166.628333717395, 37658.8117059413, 37802.243955121296, 37865.81067094647, 26053.609195862457, 31778.841105794323, 33024.50463243102, 33182.336353273284, 33392.83303583564, 21549.353519394444, 24222.87888560546, 24620.50758984846, 24899.87550062238, 25493.87253063723], "value": 19052.13071538841, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.5611792850971, 397.57062372637546, 401.24365570898846, 408.39761352005775, 428.83918530551045, 17269.22288497019, 20079.498012549695, 20254.594908101866, 21229.150833966643, 21411.250606228787, 20554.177832886668, 20853.0, 21394.25120120796, 21756.45608859779, 21881.234156804516, 5879.88240235195, 6112.449879510843, 6167.198264225644, 6167.321594624587, 6174.289956654998, 5867.735951882176, 6067.0899365095365, 6127.0, 6129.478994285469, 6218.906716399258, 6251.593646412968, 6252.562320637558, 6265.404786545271, 6270.686465676721, 6287.151234583318, 6234.875302493947, 6308.747650094014, 6978.371946524786, 6980.162380514371, 6996.475264355168, 5301.0, 6091.817245482643, 6103.328633850273, 6124.693765311717, 6127.20346354971, 14097.859021409757, 14115.435382584685, 19290.74610150336, 25554.616680749805, 26066.0, 1922.9423117306503, 2056.8289806904713, 2061.134323584098, 2141.282670305434, 2226.699395581592, 9936.0, 10002.149817265468, 10416.270861039655, 10611.257211995164, 14495.71008579833, 1756.8506676932477, 1768.2750072470353, 1768.9833589707744, 1769.3630293094475, 1774.1306759687789, 85467.57266213628, 107598.0, 111194.0, 113529.43235283853, 116407.83592164055, 11433.31400115979, 11759.529618815322, 11787.99802016821, 11798.233114847508, 11949.669814386285, 14760.0, 15209.771853422208, 15354.616134596725, 15416.0, 15416.845831541541, 7711.768646940601, 7920.2871741542385, 8349.582520873932, 8812.383133180616, 9016.819663606691, 8620.224179823846, 8688.044315125331, 8927.196552310323, 9006.181692794455, 9018.0, 4636.652251081115, 4648.604868586141, 4735.715857048588, 4735.881602959954, 4824.831130910471, 1737.3342953751333, 1866.8052446434292, 1897.4402551247415, 1965.5380985468403, 2049.600327936068, 811.2212276214782, 854.3848429131016, 996.8405055191134, 1009.9242556808268, 1022.5091955861228, 15542.611434714223, 17891.73162402565, 20578.38264852057, 20983.0, 21370.572588548453, 904.3895370624933, 928.8838895138108, 989.8119357322086, 1056.2025670618634, 1088.4884104471043, 10645.946770266099, 10893.0, 10908.127349811995, 10918.344899305908, 10937.671869843918, 589276.0, 601551.0, 605500.8108389601, 606050.0, 609394.0, 84078.89802754858, 133911.0, 136376.0, 136439.0, 139670.60329396778, 1009.6062535611118, 1033.0702367869128, 1037.8806437259811, 1061.1881910338598, 1066.3814987307344, 18031.819681803016, 18276.08619569043, 18315.908420458345, 18403.0, 18535.629287414446, 50500.74749626228, 50918.490815091376, 51814.222786657476, 53061.734691326295, 56706.86586268334, 80139.0, 82474.0, 83356.7496487541, 84410.0, 84965.57517212375, 7208.639568021683, 7539.396848252236, 7649.923500765144, 8045.275925166763, 8322.958385208036, 12141.57504487374, 13049.412776424893, 13053.738925221633, 13412.597622071353, 13422.261775602045, 7163.964180179066, 10739.239452532891, 11048.613298534512, 11623.59317423886, 11649.534018639499, 9208.756817829508, 11190.94404527972, 11355.0, 11454.427278635869, 11622.767544649229, 11279.379634120201, 11628.360440175871, 11698.824517632072, 11883.643490695293, 11906.464209110783, 8280.461769984931, 8284.378671599774, 8414.368922330972, 8433.45182563156, 8461.746147615582, 8509.404341696021, 11003.944980275048, 11284.887151128383, 11391.487383067617, 11614.477348519506], "value": 9621.812108898073, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.6700258940436, 1588.8411158884112, 1625.6667383186445, 1699.498647898869, 1708.3508266858591, 7357.484976051678, 8055.6374963126655, 8815.51514666694, 9039.32205084618, 9183.540822958843, 8788.34087443441, 9032.187103160713, 9033.864492032624, 9045.592948317326, 9050.954745226281, 2043.1520918818671, 2355.3169580821627, 2398.9880050599722, 2438.926832195037, 2453.9631905521433, 2453.9815976369873, 2471.5551200783852, 2476.5789815731277, 2480.3675062858947, 2481.3424442522746, 2395.964060539093, 2446.4617784087473, 2451.0440928037992, 2453.6564880916685, 2460.458699086198, 2030.6953956906434, 2112.7359080114984, 2364.385259832439, 2430.4409985703387, 2444.706635203769, 2433.58629033063, 2443.8044956403446, 2466.666999955001, 2468.790152837011, 2469.09877894569, 9000.504972226525, 9073.54632268384, 9084.8637270441, 9091.454512729257, 9107.043760405195, 5390.164524498709, 5567.8608034799045, 5580.386157522669, 5580.609357344987, 5592.888142237132, 2903.506403911319, 3442.621311655716, 3486.529318542002, 3488.215151590897, 3526.982365088184, 881.5680316644862, 1051.673981065872, 1060.6499855047894, 1061.347271428074, 1065.0733861540432, 12179.51281948721, 12197.573084941985, 12222.755544889053, 12225.449854756469, 12227.69430764229, 4817.445993710698, 4856.63575231852, 4890.657653964259, 4907.043126590352, 4940.975295123501, 10071.043250891154, 10102.242331825142, 10102.949485252673, 10109.494525273707, 10118.0, 5504.119340905436, 5504.4495550444635, 5509.393966663663, 5517.834464966058, 5528.723563821793, 4074.3684728867074, 4508.323751437245, 4660.697054691502, 5017.698938063728, 5034.949650503521, 3633.800140992271, 4034.1931613676793, 4104.610062044101, 4147.419361289361, 4172.603602657743, 1335.706144648176, 1340.2829486224823, 1341.1550723044616, 1353.2759973414177, 1364.3519328318969, 895.552223888056, 898.7169041751959, 919.4667093085994, 937.540605103508, 953.3231405702029, 5507.146392309199, 5962.8968640801595, 6019.247594050744, 6035.30593981689, 6079.574429789961, 398.93484396660983, 430.2663957951685, 432.0, 450.9143262780061, 451.23515640988865, 3897.785621790772, 3900.5904380040256, 3961.544422391404, 4026.355783074694, 4184.2468355696265, 10996.780064398827, 11093.389863557582, 11100.333979961228, 11242.887571124347, 11305.660830175108, 11190.216684831983, 11192.44037798107, 11196.216264861381, 11202.38386888729, 11204.327740335442, 6874.2094659113845, 8292.834143317099, 8371.832563348698, 8521.659133634708, 8534.530600817016, 5907.586468947133, 5987.503124218945, 6043.153958445909, 6104.694765261808, 6109.1141784440115, 10384.792304154025, 10399.896001040195, 10459.372437653765, 10465.581376744845, 10487.318324308895, 9834.557444915139, 10288.176945844165, 10420.635277765241, 10438.63464778729, 10557.9472102639, 7070.434365250665, 7289.8177545559665, 7530.6611202497115, 7711.537307761552, 7872.330851877426, 8503.404761666625, 8626.82746345057, 8686.261667758312, 8695.478271303742, 8732.56337183151, 7523.022007139037, 7670.578118203553, 7708.190639982945, 7825.804354891174, 7854.842903142019, 7503.66233519482, 7532.887006694795, 7555.962220188864, 7705.1909549496495, 7756.185600512091, 6161.322254551906, 7154.141503019463, 7250.057492525938, 7262.527935684164, 7278.980942668137, 4667.813287468463, 4877.317175595491, 4921.136275320563, 4924.458309586014, 4939.876503087452, 4297.474396589166, 4951.405831300123, 4998.950010499994, 5017.974910125426, 5023.372078490189], "value": 4566.152540309199, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.5000424318968, 319.331001551749, 323.11144353029164, 326.9640339562649, 329.40377915972107, 4140.565240649734, 5019.799208031674, 5267.23625074364, 5370.167624018273, 5436.891262174754, 4795.568398844103, 4949.752512374376, 5123.871903202422, 5364.356277246736, 5387.245785590011, 3079.8922037728685, 3317.817520036397, 3865.6327648873385, 4094.4267802507675, 4125.31932231183, 3480.269143479872, 4024.5371782245124, 4049.198106842456, 4064.9390259146135, 4091.365838295069, 3615.475756015376, 4026.2752704513177, 4045.766041357385, 4072.552019277877, 4077.5922407759135, 3859.3824988001925, 3887.6673995602696, 4048.554658987509, 4083.734557253799, 4121.711480196387, 2979.7616190704694, 3523.594786599548, 3542.3800834853796, 3549.2014296783277, 3556.4309710446464, 5179.549726076704, 5187.507186817246, 5212.192110222922, 5257.316548848664, 5276.894462110775, 4076.2866498362964, 4445.288753799409, 4657.5575320344515, 4689.484156742756, 4730.361401210827, 5552.305961754781, 5596.384397716248, 5630.733085055871, 5633.492985631272, 5692.57305702074, 3919.8824035278703, 3923.568407475204, 3936.1734035852505, 3937.7243592948507, 3941.2905676978135, 5118.51563046716, 5332.821035727852, 5421.186821976696, 5796.449337312991, 5893.497158224711, 3390.5083762854247, 3404.472306792451, 3548.7870727756417, 3974.4435778990965, 4007.6192761687594, 3524.6299138590593, 4047.7047344849366, 4071.328230841937, 4077.7970498702953, 4120.743173332103, 3790.5451345838123, 4183.184279065614, 4286.121345124242, 4712.293156026555, 4735.59747421473, 5426.457354264542, 5448.046591846411, 5678.545716342683, 5709.571782116275, 5754.769809207585, 1349.8920086393066, 1383.3083458270864, 1386.2167875150508, 1386.473140206715, 1391.839938407096, 770.2323168328699, 831.1024093978489, 851.2126283188027, 856.6165642487338, 873.5850471026212, 5051.459304912037, 5208.8958220835375, 5240.554552862973, 5329.9467005330225, 5385.845661735337, 5582.218489411485, 5785.392533783976, 5816.214811000461, 5851.986227093051, 5881.294244690579, 4957.553820156203, 5257.710825904535, 5281.419043905167, 5316.58095566524, 5321.441248668912, 2690.4753573053067, 2863.642044744407, 3034.150437877398, 3062.372213696187, 3075.6616772155044, 5477.260569823103, 5844.8538786529825, 6064.3935606439, 6101.60339577926, 6126.866529691932, 5359.812406565751, 5369.785208591613, 5422.783088676409, 6085.96957015221, 6113.908291375633, 6064.876588556896, 6113.633182009094, 6134.263888333339, 6136.386361363828, 6139.140520327069, 4660.86017419478, 5083.389993200692, 5853.619514731528, 5918.981319526986, 5925.229720136354, 5741.684207368635, 5761.251037365116, 5778.971105144447, 5795.420457954171, 5819.592628515963, 5849.017001329437, 5951.869144862462, 5969.910531314179, 6019.879602408015, 6158.076288556752, 3954.426608141861, 3955.169414423003, 3956.4263181839046, 3962.643362097425, 3974.642282194616, 4752.239641657318, 5206.958608278435, 5677.858053548694, 5905.202797622266, 5917.6033706349, 4471.7987690554655, 4888.853334399973, 5040.253062081101, 5705.828825135252, 5771.365149833431, 5236.659617124875, 5505.889882202414, 5717.799877004285, 5723.284589426322, 5738.196652468741, 4745.549172828576, 4756.809727610996, 5400.621956463167, 5486.478784515464, 5640.689762063126, 5189.6107791915, 5223.425423203368, 5745.621050947659, 5774.39368866263, 5787.0, 5585.0, 5630.296212973379, 5634.168895109024, 5648.378678345461, 5682.0], "value": 4126.685141146154, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.7735849056604, 692.6294432478625, 696.0846486869763, 704.6124631452701, 705.1467724053895, 13485.39315730792, 13915.956303277257, 14086.0, 14118.576442706735, 14140.15159090454, 12775.872241277584, 12778.977681785458, 12820.61538153857, 12843.871561284384, 12847.357632118403, 753.6005916864057, 907.7594437474074, 914.862770584411, 926.578406824893, 926.7868390270241, 897.5153417154729, 903.7062954539768, 905.2169873059789, 905.7192270396198, 909.958097977815, 955.6416343871049, 956.0, 956.8373376525973, 957.3777044920788, 961.418341903149, 952.7808604021079, 953.1897886796237, 953.5661274120258, 953.918916892065, 954.8376775948071, 708.7413094220573, 708.7413094220624, 709.0463326825411, 710.0414440505276, 710.6091649592703, 13367.131136476195, 13392.799108013387, 13426.664333391744, 13433.992450566247, 13453.932730336384, 11865.703357416047, 11941.0, 12029.0, 12100.152989290753, 12163.17552367143, 7604.619769011527, 7730.574818384986, 9368.126374725081, 9398.248140148771, 9699.90300096997, 3419.9316013679586, 3420.8973730788116, 3422.2984288220855, 3473.600535938349, 3676.9080772980624, 76674.61662691706, 77338.22661773367, 78236.21763782346, 78331.04172395679, 79261.60369198232, 9057.18485336323, 9095.363324567217, 9198.770030749285, 9230.676926307548, 9656.9034309656, 20147.899260503604, 20167.0, 20169.899150504152, 20173.49566260826, 20177.293794717116, 6947.444204463631, 6958.652067396712, 7081.044059052065, 7140.678669459887, 7172.928270717331, 3909.882703518899, 3981.820818063194, 3990.4413382126527, 3994.520657521116, 3994.5406278277787, 3342.131045928076, 3445.0698311455767, 3447.7069449097107, 3448.5861696596066, 3456.55064841569, 615.9106929495288, 635.7266375458505, 639.4947991086989, 640.942315191635, 655.9275584419413, 488.16280079663545, 538.4669177514311, 552.0, 560.0982418306523, 564.4693987651594, 7474.850502990019, 7608.972788673569, 7624.847503049908, 7639.197884222077, 7745.186755390603, 498.13822087788327, 498.76059491443584, 509.4497942222391, 511.3046257090354, 511.59583928696657, 3580.588232353313, 3590.0, 3626.5648122224975, 3630.6006339302658, 3789.981050094787, 71570.0, 86962.69555956665, 88192.55903720441, 89833.55083224672, 92684.0, 63242.36757632457, 73233.26766732264, 73254.6337268321, 74628.62685686645, 85062.57468712617, 70644.58710825755, 71197.64401177962, 71453.28546714467, 71462.0, 71990.8807751347, 7672.271134242238, 8004.079530853794, 8039.638216280165, 8161.224683655043, 8186.0, 43449.34825977676, 43882.0, 44182.77908610436, 44281.557184427744, 44653.55346446494, 57303.14045289408, 58944.694977452236, 61965.0, 62038.9674671145, 63810.68094659497, 10722.463876806285, 10762.515686793957, 10764.515596798006, 10889.713160236122, 11220.719482013015, 11480.024197943269, 11483.770324593628, 11559.248648837798, 11565.363904985306, 11964.58123965657, 10909.34543927367, 10917.290376125526, 11001.449927503432, 11185.72035699114, 11407.25852819564, 9746.415215087118, 9841.901580984384, 9847.0, 10087.193024557871, 10910.454477275945, 9155.902912428262, 9463.101005404476, 9562.043795620382, 9566.66516671913, 9584.089511496397, 8151.959240203998, 8260.504369737835, 8449.028361738234, 8509.914900850912, 8533.104024077631, 8061.758147255592, 8326.375521836007, 8444.324454043784, 8457.746267611981, 8514.829703406022], "value": 6508.851788694161, "unit": "score"}], "name": "dromaeo_css", "value": 7082.099813751047, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 111.0, 111.0, 112.0, 111.0, 115.0, 160.0, 155.0, 114.0], "value": 120.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 141.0, 194.0, 119.0, 139.0, 136.0, 116.0, 172.0, 138.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 185.0, 183.0, 184.0, 182.0, 184.0, 182.0, 180.0, 195.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 57.0, 126.0, 56.0, 62.0, 56.0, 60.0, 110.0, 55.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 101.0, 132.0, 78.0, 79.0, 76.0, 101.0, 79.0, 84.0, 101.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 132.0, 70.0, 129.0, 82.0, 121.0, 127.0, 104.0, 83.0], "value": 102.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 94.0, 92.0, 92.0, 93.0, 94.0, 92.0, 91.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 86.0, 86.0, 85.0, 85.0, 85.0, 85.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 56.0, 55.0, 55.0, 57.0, 115.0, 56.0, 54.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 74.0, 73.0, 69.0, 67.0, 68.0, 68.0, 68.0, 68.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 86.0, 86.0, 92.0, 93.0, 106.0, 105.0, 85.0, 85.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 137.0, 137.0, 140.0, 139.0, 148.0, 130.0, 225.0, 145.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 160.0, 164.0, 162.0, 166.0, 161.0, 161.0, 161.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 69.0, 80.0, 79.0, 81.0, 83.0, 79.0, 77.0], "value": 74.9, "unit": "score"}], "name": "kraken", "value": 1498.9000000000003, "alertThreshold": 2.0}]} 12:29:42 INFO - Return code: 0 12:29:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:29:42 ERROR - # TBPL SUCCESS # 12:29:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:42 INFO - 2016-05-16 12:29:42.520 screenresolution[1987:15210] starting screenresolution argv=screenresolution get 12:29:42 INFO - 2016-05-16 12:29:42.522 screenresolution[1987:15210] Display 0: 1600x1200x32@60 12:29:42 INFO - 2016-05-16 12:29:42.528 screenresolution[1988:15213] starting screenresolution argv=screenresolution list 12:29:42 INFO - Available Modes on Display 0 12:29:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:42 INFO - Intel Iris: 12:29:42 INFO - Chipset Model: Intel Iris 12:29:42 INFO - Type: GPU 12:29:42 INFO - Bus: Built-In 12:29:42 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:42 INFO - Vendor: Intel (0x8086) 12:29:42 INFO - Device ID: 0x0a2e 12:29:42 INFO - Revision ID: 0x0009 12:29:42 INFO - Displays: 12:29:42 INFO - Display: 12:29:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:42 INFO - Display Serial Number: 1600x1200 60 12:29:42 INFO - Main Display: Yes 12:29:42 INFO - Mirror: Off 12:29:42 INFO - Online: Yes 12:29:42 INFO - Rotation: Supported 12:29:42 INFO - Return code: 0 12:29:42 INFO - Running post-action listener: _resource_record_post_action 12:29:42 INFO - ##### 12:29:42 INFO - ##### Finished run-tests step (success) 12:29:42 INFO - ##### 12:29:42 INFO - Running post-run listener: _resource_record_post_run 12:29:42 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1089536; Write bytes: 695553536; Read time: 13; Write time: 1893 12:29:42 INFO - TinderboxPrint: CPU usage
53.8% 12:29:42 INFO - TinderboxPrint: I/O read bytes / time
1,089,536 / 13 12:29:42 INFO - TinderboxPrint: I/O write bytes / time
695,553,536 / 1,893 12:29:42 INFO - TinderboxPrint: CPU idle
2,240.3 (46.2%) 12:29:42 INFO - TinderboxPrint: CPU system
972.5 (20.1%) 12:29:42 INFO - TinderboxPrint: CPU user
1,636.8 (33.8%) 12:29:42 INFO - TinderboxPrint: Swap in / out
396,562,432 / 0 12:29:42 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 388672000; Write bytes: 383576064; Read time: 28475; Write time: 735 12:29:42 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1089536; Write bytes: 296748544; Read time: 13; Write time: 1121 12:29:43 INFO - Running post-run listener: _upload_blobber_files 12:29:43 INFO - Blob upload gear active. 12:29:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:43 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:29:43 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:29:43 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:29:43 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:29:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:29:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:44 INFO - (blobuploader) - INFO - Done attempting. 12:29:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:29:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:29:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:45 INFO - (blobuploader) - INFO - Done attempting. 12:29:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:47 INFO - (blobuploader) - INFO - Done attempting. 12:29:47 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:47 INFO - Return code: 0 12:29:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bcc271815aae44f304a6f30bf84661d68380da28d75c913272e98a1da8b1c48bf593cc0c5e490b06924dfd3cb79ad8f13a4277fc44793f8a3b81c97c791945d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06179b67b94315f586657401ae56e13b6aa94b0055b1645ff09a88aff952bf02bd458aba9f539389999bc0c92e3464200f8d0424c64e46f19e258593efcfc8dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/28020862e43f344bdc259ed61667fcb9d62de331469b12b646ec6e1449df9e54ffa13bc413ad2342b77230f35457f6ab5ce61f5b2de3972a6d6f45731a6adfa9"} 12:29:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:47 INFO - Contents: 12:29:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bcc271815aae44f304a6f30bf84661d68380da28d75c913272e98a1da8b1c48bf593cc0c5e490b06924dfd3cb79ad8f13a4277fc44793f8a3b81c97c791945d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06179b67b94315f586657401ae56e13b6aa94b0055b1645ff09a88aff952bf02bd458aba9f539389999bc0c92e3464200f8d0424c64e46f19e258593efcfc8dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/28020862e43f344bdc259ed61667fcb9d62de331469b12b646ec6e1449df9e54ffa13bc413ad2342b77230f35457f6ab5ce61f5b2de3972a6d6f45731a6adfa9"} 12:29:47 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:47 INFO - Copying logs to upload dir... 12:29:47 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.429744 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-16 12:29:48.065148) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 12:29:48.072743) ========= 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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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/bcc271815aae44f304a6f30bf84661d68380da28d75c913272e98a1da8b1c48bf593cc0c5e490b06924dfd3cb79ad8f13a4277fc44793f8a3b81c97c791945d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06179b67b94315f586657401ae56e13b6aa94b0055b1645ff09a88aff952bf02bd458aba9f539389999bc0c92e3464200f8d0424c64e46f19e258593efcfc8dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/28020862e43f344bdc259ed61667fcb9d62de331469b12b646ec6e1449df9e54ffa13bc413ad2342b77230f35457f6ab5ce61f5b2de3972a6d6f45731a6adfa9"} build_url:https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010978 build_url: 'https://queue.taskcluster.net/v1/task/R-z1jmLYS8SxpQBAEay7Dg/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/bcc271815aae44f304a6f30bf84661d68380da28d75c913272e98a1da8b1c48bf593cc0c5e490b06924dfd3cb79ad8f13a4277fc44793f8a3b81c97c791945d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/06179b67b94315f586657401ae56e13b6aa94b0055b1645ff09a88aff952bf02bd458aba9f539389999bc0c92e3464200f8d0424c64e46f19e258593efcfc8dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/28020862e43f344bdc259ed61667fcb9d62de331469b12b646ec6e1449df9e54ffa13bc413ad2342b77230f35457f6ab5ce61f5b2de3972a6d6f45731a6adfa9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 12:29:48.100839) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:29:48.101158) ========= 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.7Z9Reb6wCR/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.OKLZdRpzRB/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.005286 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:29:48.156479) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 12:29:48.156800) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 12:29:48.157276) ========= ========= Total master_lag: 0.21 =========