builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0088 starttime: 1463590450.69 results: success (0) buildid: 20160518085840 builduid: b78dbb4849ce401ab5db6e4a7698e765 revision: 47b83c8478a06374aa9306122a778654b912f713 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:10.695135) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:10.695616) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:10.695899) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:10.791711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:10.792109) ========= 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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-18 09:54:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 178M=0s 2016-05-18 09:54:10 (178 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132935 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:10.953514) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:10.953853) ========= 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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.026924 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:11.004200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 09:54:11.004625) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 47b83c8478a06374aa9306122a778654b912f713 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 47b83c8478a06374aa9306122a778654b912f713 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3JpeZtBT6R/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.V3XgOlAjT4/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-18 09:54:11,082 truncating revision to first 12 chars 2016-05-18 09:54:11,082 Setting DEBUG logging. 2016-05-18 09:54:11,082 attempt 1/10 2016-05-18 09:54:11,082 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/47b83c8478a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 09:54:12,016 unpacking tar archive at: mozilla-central-47b83c8478a0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.183619 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 09:54:12.208008) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:12.208412) ========= script_repo_revision: 47b83c8478a06374aa9306122a778654b912f713 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:12.208865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:12.209144) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 09:54:12.274100) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-18 09:54:12.274458) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3JpeZtBT6R/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.V3XgOlAjT4/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:54:12 INFO - MultiFileLogger online at 20160518 09:54:12 in /builds/slave/test 09:54:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:54:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:54:12 INFO - {'append_to_log': False, 09:54:12 INFO - 'base_work_dir': '/builds/slave/test', 09:54:12 INFO - 'blob_upload_branch': 'Firefox', 09:54:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:54:12 INFO - 'branch': 'Firefox', 09:54:12 INFO - 'buildbot_json_path': 'buildprops.json', 09:54:12 INFO - 'config_files': ('talos/mac_config.py',), 09:54:12 INFO - 'default_actions': ('clobber', 09:54:12 INFO - 'read-buildbot-config', 09:54:12 INFO - 'download-and-extract', 09:54:12 INFO - 'populate-webroot', 09:54:12 INFO - 'create-virtualenv', 09:54:12 INFO - 'install', 09:54:12 INFO - 'run-tests'), 09:54:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:54:12 INFO - 'download_minidump_stackwalk': True, 09:54:12 INFO - 'download_symbols': 'ondemand', 09:54:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:54:12 INFO - 'tooltool.py': '/tools/tooltool.py', 09:54:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:54:12 INFO - '/tools/misc-python/virtualenv.py')}, 09:54:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:54:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:54:12 INFO - 'installer_path': 'installer.exe', 09:54:12 INFO - 'log_level': 'info', 09:54:12 INFO - 'log_name': 'talos', 09:54:12 INFO - 'log_to_console': True, 09:54:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:54:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:54:12 INFO - 'opt_config_files': (), 09:54:12 INFO - 'pip_index': False, 09:54:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:12 INFO - 'cmd': ('bash', 09:54:12 INFO - '-c', 09:54:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:12 INFO - 'enabled': True, 09:54:12 INFO - 'halt_on_failure': False, 09:54:12 INFO - 'name': 'check_screen_resolution'},), 09:54:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:12 INFO - 'cmd': ('bash', 09:54:12 INFO - '-c', 09:54:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:12 INFO - 'enabled': True, 09:54:12 INFO - 'halt_on_failure': False, 09:54:12 INFO - 'name': 'check_screen_resolution'},), 09:54:12 INFO - 'run_cmd_checks_enabled': True, 09:54:12 INFO - 'sps_profile': False, 09:54:12 INFO - 'sps_profile_interval': 0, 09:54:12 INFO - 'suite': 'dromaeojs', 09:54:12 INFO - 'system_bits': '32', 09:54:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:54:12 INFO - 'title': 't-yosemite-r7-0088', 09:54:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:54:12 INFO - 'use_talos_json': True, 09:54:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:54:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:54:12 INFO - 'work_dir': 'build'} 09:54:12 INFO - ##### 09:54:12 INFO - ##### Running clobber step. 09:54:12 INFO - ##### 09:54:12 INFO - Running pre-action listener: _resource_record_pre_action 09:54:12 INFO - Running main action method: clobber 09:54:12 INFO - rmtree: /builds/slave/test/build 09:54:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:54:12 INFO - Running post-action listener: _resource_record_post_action 09:54:12 INFO - ##### 09:54:12 INFO - ##### Finished clobber step (success) 09:54:12 INFO - ##### 09:54:12 INFO - ##### 09:54:12 INFO - ##### Running read-buildbot-config step. 09:54:12 INFO - ##### 09:54:12 INFO - Running pre-action listener: _resource_record_pre_action 09:54:12 INFO - Running main action method: read_buildbot_config 09:54:12 INFO - Using buildbot properties: 09:54:12 INFO - { 09:54:12 INFO - "project": "", 09:54:12 INFO - "product": "firefox", 09:54:12 INFO - "script_repo_revision": "production", 09:54:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:54:12 INFO - "repository": "", 09:54:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:54:12 INFO - "buildid": "20160518085840", 09:54:12 INFO - "pgo_build": "False", 09:54:12 INFO - "basedir": "/builds/slave/test", 09:54:12 INFO - "buildnumber": 254, 09:54:12 INFO - "slavename": "t-yosemite-r7-0088", 09:54:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:54:12 INFO - "platform": "yosemite_r7", 09:54:12 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:54:12 INFO - "branch": "mozilla-central", 09:54:12 INFO - "repo_path": "mozilla-central", 09:54:12 INFO - "revision": "47b83c8478a06374aa9306122a778654b912f713", 09:54:12 INFO - "stage_platform": "macosx64", 09:54:12 INFO - "builduid": "b78dbb4849ce401ab5db6e4a7698e765", 09:54:12 INFO - "slavebuilddir": "test" 09:54:12 INFO - } 09:54:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:54:12 INFO - Running post-action listener: _resource_record_post_action 09:54:12 INFO - ##### 09:54:12 INFO - ##### Finished read-buildbot-config step (success) 09:54:12 INFO - ##### 09:54:12 INFO - ##### 09:54:12 INFO - ##### Running download-and-extract step. 09:54:12 INFO - ##### 09:54:12 INFO - Running pre-action listener: _resource_record_pre_action 09:54:12 INFO - Running main action method: download_and_extract 09:54:12 INFO - mkdir: /builds/slave/test/build/tests 09:54:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:12 INFO - https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:54:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:54:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:54:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/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 09:54:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/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 09:54:14 INFO - Downloaded 1199 bytes. 09:54:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:54:14 INFO - Using the following test package requirements: 09:54:14 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:54:14 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:54:14 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:54:14 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:54:14 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:54:14 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:54:14 INFO - u'jsshell-mac.zip'], 09:54:14 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:54:14 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:54:14 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:54:14 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:54:14 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:54:14 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:54:14 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:54:14 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:54:14 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:54:14 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:54:14 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:54:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:54:14 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')]} 09:54:14 INFO - https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:54:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:54:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:54:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/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 09:54:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/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 09:54:16 INFO - Downloaded 18306045 bytes. 09:54:16 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'] 09:54:16 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 09:54:19 INFO - Return code: 0 09:54:19 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 09:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:19 INFO - https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:54:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:54:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:54:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/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 09:54:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/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 09:54:21 INFO - Downloaded 18306045 bytes. 09:54:21 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'] 09:54:21 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 09:54:24 INFO - Return code: 0 09:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:24 INFO - https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:54:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:54:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:54:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/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 09:54:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/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 09:54:26 INFO - Downloaded 10840194 bytes. 09:54:26 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'] 09:54:26 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 09:54:26 INFO - Return code: 0 09:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:26 INFO - https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:54:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:54:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:54:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:54:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HGjGpvzDRlekmMZPn8Hz9w/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 09:54:32 INFO - Downloaded 110190562 bytes. 09:54:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:54:32 INFO - mkdir: /builds/slave/test/properties 09:54:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:54:32 INFO - Writing to file /builds/slave/test/properties/build_url 09:54:32 INFO - Contents: 09:54:32 INFO - build_url:https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:54:33 INFO - Running post-action listener: _resource_record_post_action 09:54:33 INFO - Running post-action listener: set_extra_try_arguments 09:54:33 INFO - ##### 09:54:33 INFO - ##### Finished download-and-extract step (success) 09:54:33 INFO - ##### 09:54:33 INFO - ##### 09:54:33 INFO - ##### Running populate-webroot step. 09:54:33 INFO - ##### 09:54:33 INFO - Running pre-action listener: _resource_record_pre_action 09:54:33 INFO - Running main action method: populate_webroot 09:54:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:54:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:54:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:54:33 INFO - '--tppagecycles', 09:54:33 INFO - '7'], 09:54:33 INFO - 'tests': ['tsvgm']}}, 09:54:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:54:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:54:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:54:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:54:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:33 INFO - 'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:54:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:54:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:33 INFO - 'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['damp', 'tps']}, 09:54:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:33 INFO - 'tests': ['damp', 'tps']}, 09:54:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['dromaeo_dom']}, 09:54:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:54:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['a11yr', 09:54:33 INFO - 'ts_paint', 09:54:33 INFO - 'tpaint', 09:54:33 INFO - 'sessionrestore', 09:54:33 INFO - 'sessionrestore_no_auto_restore', 09:54:33 INFO - 'tabpaint']}, 09:54:33 INFO - 'other-e10s': {'tests': ['a11yr', 09:54:33 INFO - 'ts_paint', 09:54:33 INFO - 'tpaint', 09:54:33 INFO - 'sessionrestore', 09:54:33 INFO - 'sessionrestore_no_auto_restore', 09:54:33 INFO - 'tabpaint']}, 09:54:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:54:33 INFO - 'ts_paint', 09:54:33 INFO - 'tpaint', 09:54:33 INFO - 'sessionrestore', 09:54:33 INFO - 'sessionrestore_no_auto_restore', 09:54:33 INFO - 'tabpaint']}, 09:54:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:54:33 INFO - 'ts_paint', 09:54:33 INFO - 'tpaint', 09:54:33 INFO - 'sessionrestore', 09:54:33 INFO - 'sessionrestore_no_auto_restore', 09:54:33 INFO - 'tabpaint']}, 09:54:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['a11yr', 09:54:33 INFO - 'ts_paint', 09:54:33 INFO - 'tpaint', 09:54:33 INFO - 'sessionrestore', 09:54:33 INFO - 'sessionrestore_no_auto_restore', 09:54:33 INFO - 'tabpaint']}, 09:54:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['a11yr', 09:54:33 INFO - 'ts_paint', 09:54:33 INFO - 'tpaint', 09:54:33 INFO - 'sessionrestore', 09:54:33 INFO - 'sessionrestore_no_auto_restore', 09:54:33 INFO - 'tabpaint']}, 09:54:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['tsvgx', 09:54:33 INFO - 'tsvgr_opacity', 09:54:33 INFO - 'tart', 09:54:33 INFO - 'tscrollx', 09:54:33 INFO - 'cart']}, 09:54:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:54:33 INFO - 'tsvgr_opacity', 09:54:33 INFO - 'tart', 09:54:33 INFO - 'tscrollx', 09:54:33 INFO - 'cart']}, 09:54:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:33 INFO - 'talos_options': ['--disable-e10s'], 09:54:33 INFO - 'tests': ['tp5o']}, 09:54:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:33 INFO - 'tests': ['tp5o']}, 09:54:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:33 INFO - 'talos_options': ['--disable-e10s', 09:54:33 INFO - '--xperf_path', 09:54:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:54:33 INFO - 'tests': ['tp5n']}, 09:54:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:54:33 INFO - 'talos_options': ['--xperf_path', 09:54:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:54:33 INFO - 'tests': ['tp5n']}}, 09:54:33 INFO - 'talos.zip': {'path': '', 09:54:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:54:33 INFO - Running post-action listener: _resource_record_post_action 09:54:33 INFO - ##### 09:54:33 INFO - ##### Finished populate-webroot step (success) 09:54:33 INFO - ##### 09:54:33 INFO - ##### 09:54:33 INFO - ##### Running create-virtualenv step. 09:54:33 INFO - ##### 09:54:33 INFO - Running pre-action listener: _resource_record_pre_action 09:54:33 INFO - Running main action method: create_virtualenv 09:54:33 INFO - Creating virtualenv /builds/slave/test/build/venv 09:54:33 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 09:54:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:54:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:54:33 INFO - Using real prefix '/tools/python27' 09:54:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:54:34 INFO - Installing distribute.............................................................................................................................................................................................done. 09:54:37 INFO - Installing pip.................done. 09:54:37 INFO - Return code: 0 09:54:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:37 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 0x109748a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feca9cac4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, '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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:54:37 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 09:54:37 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 09:54:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:54:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:37 INFO - 'HOME': '/Users/cltbld', 09:54:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:37 INFO - 'LOGNAME': 'cltbld', 09:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:37 INFO - 'MOZ_NO_REMOTE': '1', 09:54:37 INFO - 'NO_EM_RESTART': '1', 09:54:37 INFO - 'PAGER': '/bin/cat', 09:54:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:37 INFO - 'PWD': '/builds/slave/test', 09:54:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:37 INFO - 'SHELL': '/bin/bash', 09:54:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:54:37 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:54:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:37 INFO - 'USER': 'cltbld', 09:54:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:37 INFO - 'XPC_FLAGS': '0x0', 09:54:37 INFO - 'XPC_SERVICE_NAME': '0', 09:54:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:37 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 09:54:37 INFO - Cleaning up... 09:54:37 INFO - Return code: 0 09:54:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:37 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 0x109748a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feca9cac4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, '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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:54:37 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 09:54:37 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 09:54:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:54:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:37 INFO - 'HOME': '/Users/cltbld', 09:54:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:37 INFO - 'LOGNAME': 'cltbld', 09:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:37 INFO - 'MOZ_NO_REMOTE': '1', 09:54:37 INFO - 'NO_EM_RESTART': '1', 09:54:37 INFO - 'PAGER': '/bin/cat', 09:54:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:37 INFO - 'PWD': '/builds/slave/test', 09:54:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:37 INFO - 'SHELL': '/bin/bash', 09:54:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:54:37 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:54:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:37 INFO - 'USER': 'cltbld', 09:54:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:37 INFO - 'XPC_FLAGS': '0x0', 09:54:37 INFO - 'XPC_SERVICE_NAME': '0', 09:54:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:37 INFO - Downloading/unpacking psutil>=3.1.1 09:54:37 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 09:54:37 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 09:54:37 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 09:54:37 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 09:54:37 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 09:54:37 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 09:54:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:54:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:54:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:54:40 INFO - Installing collected packages: psutil 09:54:40 INFO - Running setup.py install for psutil 09:54:40 INFO - building 'psutil._psutil_osx' extension 09:54:40 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 09:54:41 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 09:54:41 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 09:54:41 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 09:54:41 INFO - building 'psutil._psutil_posix' extension 09:54:41 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 09:54:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:54:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:54:41 INFO - ^ 09:54:41 INFO - 1 warning generated. 09:54:41 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 09:54:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:54:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:54:41 INFO - Successfully installed psutil 09:54:41 INFO - Cleaning up... 09:54:41 INFO - Return code: 0 09:54:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:54: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')]} 09:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54: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')]} 09:54:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:41 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 0x109748a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feca9cac4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, '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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:54:41 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 09:54:41 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 09:54:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:54:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:41 INFO - 'HOME': '/Users/cltbld', 09:54:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:41 INFO - 'LOGNAME': 'cltbld', 09:54:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:41 INFO - 'MOZ_NO_REMOTE': '1', 09:54:41 INFO - 'NO_EM_RESTART': '1', 09:54:41 INFO - 'PAGER': '/bin/cat', 09:54:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:41 INFO - 'PWD': '/builds/slave/test', 09:54:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:41 INFO - 'SHELL': '/bin/bash', 09:54:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:54:41 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:54:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:41 INFO - 'USER': 'cltbld', 09:54:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:41 INFO - 'XPC_FLAGS': '0x0', 09:54:41 INFO - 'XPC_SERVICE_NAME': '0', 09:54:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:54:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:54:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:54:44 INFO - Installing collected packages: mozsystemmonitor 09:54:44 INFO - Running setup.py install for mozsystemmonitor 09:54:44 INFO - Successfully installed mozsystemmonitor 09:54:44 INFO - Cleaning up... 09:54:44 INFO - Return code: 0 09:54:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:54:44 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')]} 09:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:44 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')]} 09:54:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:44 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 0x109748a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feca9cac4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, '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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:54:44 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 09:54:44 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 09:54:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:54:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:44 INFO - 'HOME': '/Users/cltbld', 09:54:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:44 INFO - 'LOGNAME': 'cltbld', 09:54:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:44 INFO - 'MOZ_NO_REMOTE': '1', 09:54:44 INFO - 'NO_EM_RESTART': '1', 09:54:44 INFO - 'PAGER': '/bin/cat', 09:54:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:44 INFO - 'PWD': '/builds/slave/test', 09:54:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:44 INFO - 'SHELL': '/bin/bash', 09:54:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:54:44 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:54:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:44 INFO - 'USER': 'cltbld', 09:54:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:44 INFO - 'XPC_FLAGS': '0x0', 09:54:44 INFO - 'XPC_SERVICE_NAME': '0', 09:54:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:45 INFO - Downloading/unpacking blobuploader==1.2.4 09:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:47 INFO - Downloading blobuploader-1.2.4.tar.gz 09:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:54:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:54:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:54:48 INFO - Downloading docopt-0.6.1.tar.gz 09:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:54:49 INFO - Installing collected packages: blobuploader, requests, docopt 09:54:49 INFO - Running setup.py install for blobuploader 09:54:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:54:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:54:49 INFO - Running setup.py install for requests 09:54:49 INFO - Running setup.py install for docopt 09:54:49 INFO - Successfully installed blobuploader requests docopt 09:54:49 INFO - Cleaning up... 09:54:49 INFO - Return code: 0 09:54:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:49 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 0x109748a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feca9cac4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, '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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:54:49 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 09:54:49 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 09:54:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:54:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:49 INFO - 'HOME': '/Users/cltbld', 09:54:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:49 INFO - 'LOGNAME': 'cltbld', 09:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:49 INFO - 'MOZ_NO_REMOTE': '1', 09:54:49 INFO - 'NO_EM_RESTART': '1', 09:54:49 INFO - 'PAGER': '/bin/cat', 09:54:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:49 INFO - 'PWD': '/builds/slave/test', 09:54:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:49 INFO - 'SHELL': '/bin/bash', 09:54:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:54:49 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:54:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:49 INFO - 'USER': 'cltbld', 09:54:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:49 INFO - 'XPC_FLAGS': '0x0', 09:54:49 INFO - 'XPC_SERVICE_NAME': '0', 09:54:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:54:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Ij24iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:54:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-GSuD8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:54:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ZFpRPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:54:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-2JSxun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:54:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-F7uJwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:54:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-888zJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:54:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-CRsjLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:54:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-fdxojQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:54:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ldV8rF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:54:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-wgIfql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:54:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ifMr4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:54:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-mHh2kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:54:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-9rf86r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:54:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-a7lxZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-OffPAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:54:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-p5HSli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:54:51 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-udDTuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:54:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:54:51 INFO - Running setup.py install for manifestparser 09:54:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:54:52 INFO - Running setup.py install for mozcrash 09:54:52 INFO - Running setup.py install for mozdebug 09:54:52 INFO - Running setup.py install for mozdevice 09:54:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:54:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:54:52 INFO - Running setup.py install for mozfile 09:54:52 INFO - Running setup.py install for mozhttpd 09:54:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:54:52 INFO - Running setup.py install for mozinfo 09:54:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:54:52 INFO - Running setup.py install for mozInstall 09:54:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:54:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:54:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:54:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:54:53 INFO - Running setup.py install for mozleak 09:54:53 INFO - Running setup.py install for mozlog 09:54:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:54:53 INFO - Running setup.py install for moznetwork 09:54:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:54:53 INFO - Running setup.py install for mozprocess 09:54:53 INFO - Running setup.py install for mozprofile 09:54:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:54:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:54:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:54:53 INFO - Running setup.py install for mozrunner 09:54:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:54:54 INFO - Running setup.py install for mozscreenshot 09:54:54 INFO - Running setup.py install for moztest 09:54:54 INFO - Running setup.py install for mozversion 09:54:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:54:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:54:54 INFO - Cleaning up... 09:54:54 INFO - Return code: 0 09:54:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:54 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 0x109748a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feca9cac4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, '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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:54:54 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 09:54:54 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 09:54:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:54:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:54 INFO - 'HOME': '/Users/cltbld', 09:54:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:54 INFO - 'LOGNAME': 'cltbld', 09:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:54 INFO - 'MOZ_NO_REMOTE': '1', 09:54:54 INFO - 'NO_EM_RESTART': '1', 09:54:54 INFO - 'PAGER': '/bin/cat', 09:54:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:54 INFO - 'PWD': '/builds/slave/test', 09:54:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:54 INFO - 'SHELL': '/bin/bash', 09:54:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:54:54 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:54:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:54 INFO - 'USER': 'cltbld', 09:54:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:54 INFO - 'XPC_FLAGS': '0x0', 09:54:54 INFO - 'XPC_SERVICE_NAME': '0', 09:54:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:54:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-U9FzmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:54:54 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)) 09:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:54:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-n_84Mc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-sOs0yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-WgJnPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-r3HYaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-raVuDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ofp6LW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-bErNEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-GiK3QT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-KjLdC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Dmsm17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:54:55 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)) 09:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:54:55 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-vbtjo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:54:56 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)) 09:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:54:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Qr8OS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:54:56 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)) 09:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:54:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-yklMdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:54:56 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)) 09:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-MVuYvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:54:56 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)) 09:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:54:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ODWhiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:54:56 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)) 09:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:54:56 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-x_PD0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:54:56 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)) 09:54:56 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)) 09:54:56 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)) 09:54:56 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)) 09:54:56 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)) 09:54:56 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)) 09:54:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:54:56 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 09:54:56 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 09:54:56 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 09:54:56 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 09:54:56 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 09:54:56 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 09:54:59 INFO - Downloading blessings-1.6.tar.gz 09:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:54:59 INFO - Installing collected packages: blessings 09:54:59 INFO - Running setup.py install for blessings 09:54:59 INFO - Successfully installed blessings 09:54:59 INFO - Cleaning up... 09:54:59 INFO - Return code: 0 09:54:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:54:59 INFO - Reading from file tmpfile_stdout 09:54:59 INFO - Current package versions: 09:54:59 INFO - blessings == 1.6 09:54:59 INFO - blobuploader == 1.2.4 09:54:59 INFO - docopt == 0.6.1 09:54:59 INFO - manifestparser == 1.1 09:54:59 INFO - mozInstall == 1.12 09:54:59 INFO - mozcrash == 0.17 09:54:59 INFO - mozdebug == 0.1 09:54:59 INFO - mozdevice == 0.48 09:54:59 INFO - mozfile == 1.2 09:54:59 INFO - mozhttpd == 0.7 09:54:59 INFO - mozinfo == 0.9 09:54:59 INFO - mozleak == 0.1 09:54:59 INFO - mozlog == 3.2 09:54:59 INFO - moznetwork == 0.27 09:54:59 INFO - mozprocess == 0.22 09:54:59 INFO - mozprofile == 0.28 09:54:59 INFO - mozrunner == 6.11 09:54:59 INFO - mozscreenshot == 0.1 09:54:59 INFO - mozsystemmonitor == 0.3 09:54:59 INFO - moztest == 0.7 09:54:59 INFO - mozversion == 1.4 09:54:59 INFO - psutil == 3.1.1 09:54:59 INFO - requests == 1.2.3 09:54:59 INFO - wsgiref == 0.1.2 09:54:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:54:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:54:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:54:59 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 0x109748a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feca9cac4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, '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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:54:59 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 09:54:59 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 09:54:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:54:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:59 INFO - 'HOME': '/Users/cltbld', 09:54:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:59 INFO - 'LOGNAME': 'cltbld', 09:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:59 INFO - 'MOZ_NO_REMOTE': '1', 09:54:59 INFO - 'NO_EM_RESTART': '1', 09:54:59 INFO - 'PAGER': '/bin/cat', 09:54:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:59 INFO - 'PWD': '/builds/slave/test', 09:54:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:59 INFO - 'SHELL': '/bin/bash', 09:54:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:54:59 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:54:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:59 INFO - 'USER': 'cltbld', 09:54:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:59 INFO - 'XPC_FLAGS': '0x0', 09:54:59 INFO - 'XPC_SERVICE_NAME': '0', 09:54:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:00 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)) 09:55:00 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)) 09:55:00 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)) 09:55:00 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)) 09:55:00 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)) 09:55:00 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)) 09:55:00 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)) 09:55:00 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)) 09:55:00 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)) 09:55:00 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)) 09:55:00 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)) 09:55:00 INFO - Cleaning up... 09:55:00 INFO - Return code: 0 09:55:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:55: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')]} 09:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55: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')]} 09:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109748a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feca9cac4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, '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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:55: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', 'jsonschema'] in /builds/slave/test/build 09:55: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 jsonschema 09:55:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:55:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:00 INFO - 'HOME': '/Users/cltbld', 09:55:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:00 INFO - 'LOGNAME': 'cltbld', 09:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:00 INFO - 'MOZ_NO_REMOTE': '1', 09:55:00 INFO - 'NO_EM_RESTART': '1', 09:55:00 INFO - 'PAGER': '/bin/cat', 09:55:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:00 INFO - 'PWD': '/builds/slave/test', 09:55:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:00 INFO - 'SHELL': '/bin/bash', 09:55:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:55:00 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:55:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:00 INFO - 'USER': 'cltbld', 09:55:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:00 INFO - 'XPC_FLAGS': '0x0', 09:55:00 INFO - 'XPC_SERVICE_NAME': '0', 09:55:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:00 INFO - Downloading/unpacking jsonschema 09:55: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 09:55: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 09:55: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 09:55: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 09:55: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 09:55: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 09:55:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:55:02 INFO - Downloading/unpacking functools32 (from jsonschema) 09:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:03 INFO - Downloading functools32-3.2.3-2.tar.gz 09:55:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:55:03 INFO - warning: no files found matching '*.txt' 09:55:03 INFO - no previously-included directories found matching 'build' 09:55:03 INFO - no previously-included directories found matching 'dist' 09:55:03 INFO - no previously-included directories found matching '.git*' 09:55:03 INFO - Installing collected packages: jsonschema, functools32 09:55:03 INFO - Running setup.py install for functools32 09:55:03 INFO - warning: no files found matching '*.txt' 09:55:03 INFO - no previously-included directories found matching 'build' 09:55:03 INFO - no previously-included directories found matching 'dist' 09:55:03 INFO - no previously-included directories found matching '.git*' 09:55:03 INFO - Successfully installed jsonschema functools32 09:55:03 INFO - Cleaning up... 09:55:03 INFO - Return code: 0 09:55:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:55: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')]} 09:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55: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')]} 09:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:03 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 0x109748a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10974e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feca9cac4e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, '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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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 09:55:03 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 09:55:03 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 09:55:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:55:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:03 INFO - 'HOME': '/Users/cltbld', 09:55:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:03 INFO - 'LOGNAME': 'cltbld', 09:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:03 INFO - 'MOZ_NO_REMOTE': '1', 09:55:03 INFO - 'NO_EM_RESTART': '1', 09:55:03 INFO - 'PAGER': '/bin/cat', 09:55:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:03 INFO - 'PWD': '/builds/slave/test', 09:55:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:03 INFO - 'SHELL': '/bin/bash', 09:55:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:55:03 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:55:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:03 INFO - 'USER': 'cltbld', 09:55:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:03 INFO - 'XPC_FLAGS': '0x0', 09:55:03 INFO - 'XPC_SERVICE_NAME': '0', 09:55:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:03 INFO - Downloading/unpacking flake8 09:55:03 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 09:55:03 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 09:55:03 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 09:55:03 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 09:55:03 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 09:55:03 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 09:55:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:55:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:55:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:55:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:55:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:55:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:55:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:55:07 INFO - no previously-included directories found matching 'docs/_build' 09:55:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:55:07 INFO - Running setup.py install for pep8 09:55:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:55:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:55:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:55:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:55:07 INFO - no previously-included directories found matching 'docs/_build' 09:55:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:55:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:55:07 INFO - Cleaning up... 09:55:07 INFO - Return code: 0 09:55:07 INFO - Running post-action listener: _resource_record_post_action 09:55:07 INFO - Running post-action listener: _start_resource_monitoring 09:55:07 INFO - Starting resource monitoring. 09:55:07 INFO - ##### 09:55:07 INFO - ##### Finished create-virtualenv step (success) 09:55:07 INFO - ##### 09:55:07 INFO - ##### 09:55:07 INFO - ##### Running install step. 09:55:07 INFO - ##### 09:55:07 INFO - Running pre-action listener: _resource_record_pre_action 09:55:07 INFO - Running main action method: install 09:55:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:55:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:55:08 INFO - Reading from file tmpfile_stdout 09:55:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:55:08 INFO - Reading from file tmpfile_stdout 09:55:08 INFO - Output received: 09:55:08 INFO - Usage: mozinstall [options] installer 09:55:08 INFO - Options: 09:55:08 INFO - -h, --help show this help message and exit 09:55:08 INFO - -d DEST, --destination=DEST 09:55:08 INFO - Directory to install application into. [default: 09:55:08 INFO - "/builds/slave/test"] 09:55:08 INFO - --app=APP Application being installed. [default: firefox] 09:55:08 INFO - mkdir: /builds/slave/test/build/application 09:55:08 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'] 09:55:08 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 09:55:41 INFO - Reading from file tmpfile_stdout 09:55:41 INFO - Output received: 09:55:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:55:41 INFO - Running post-action listener: _resource_record_post_action 09:55:41 INFO - ##### 09:55:41 INFO - ##### Finished install step (success) 09:55:41 INFO - ##### 09:55:41 INFO - ##### 09:55:41 INFO - ##### Running run-tests step. 09:55:41 INFO - ##### 09:55:41 INFO - Running pre-action listener: _resource_record_pre_action 09:55:41 INFO - Running main action method: run_tests 09:55:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:55:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:55:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:55:41 INFO - 2016-05-18 09:55:41.650 screenresolution[1651:10295] starting screenresolution argv=screenresolution get 09:55:41 INFO - 2016-05-18 09:55:41.653 screenresolution[1651:10295] Display 0: 1600x1200x32@60 09:55:41 INFO - 2016-05-18 09:55:41.659 screenresolution[1652:10297] starting screenresolution argv=screenresolution list 09:55:41 INFO - Available Modes on Display 0 09:55:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:55:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:55:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:55:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:55:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:55:41 INFO - Intel Iris: 09:55:41 INFO - Chipset Model: Intel Iris 09:55:41 INFO - Type: GPU 09:55:41 INFO - Bus: Built-In 09:55:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:55:41 INFO - Vendor: Intel (0x8086) 09:55:41 INFO - Device ID: 0x0a2e 09:55:41 INFO - Revision ID: 0x0009 09:55:41 INFO - Displays: 09:55:41 INFO - Display: 09:55:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:55:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:55:41 INFO - Display Serial Number: 1600x1200 60 09:55:41 INFO - Main Display: Yes 09:55:41 INFO - Mirror: Off 09:55:41 INFO - Online: Yes 09:55:41 INFO - Rotation: Supported 09:55:41 INFO - Return code: 0 09:55:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:55:41 INFO - Python 2.7.3 09:55:41 INFO - Return code: 0 09:55:41 INFO - grabbing minidump binary from tooltool 09:55: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')]} 09:55:41 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 0x1095ecd30>, '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 0x109410db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095e3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:55:41 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 09:55:41 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 09:55:41 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 09:55:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:55:42 INFO - Return code: 0 09:55:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:55:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:55:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:55:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:55:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:55:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:55:43 INFO - Return code: 0 09:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 09:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0088 --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 09:55:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3JpeZtBT6R/Render', 09:55:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:43 INFO - 'HOME': '/Users/cltbld', 09:55:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:43 INFO - 'LOGNAME': 'cltbld', 09:55:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:55:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:43 INFO - 'MOZ_NO_REMOTE': '1', 09:55:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:55:43 INFO - 'NO_EM_RESTART': '1', 09:55:43 INFO - 'PAGER': '/bin/cat', 09:55:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:43 INFO - 'PWD': '/builds/slave/test', 09:55:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:55:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:43 INFO - 'SHELL': '/bin/bash', 09:55:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V3XgOlAjT4/Listeners', 09:55:43 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 09:55:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:43 INFO - 'USER': 'cltbld', 09:55:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:43 INFO - 'XPC_FLAGS': '0x0', 09:55:43 INFO - 'XPC_SERVICE_NAME': '0', 09:55:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 09:55:43 INFO - mozversion application_buildid: 20160518085840 09:55:43 INFO - mozversion application_changeset: 47b83c8478a06374aa9306122a778654b912f713 09:55:43 INFO - mozversion application_display_name: Nightly 09:55:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:55:43 INFO - mozversion application_name: Firefox 09:55:43 INFO - mozversion application_remotingname: firefox 09:55:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:55:43 INFO - mozversion application_vendor: Mozilla 09:55:43 INFO - mozversion application_version: 49.0a1 09:55:43 INFO - mozversion platform_buildid: 20160518085840 09:55:43 INFO - mozversion platform_changeset: 47b83c8478a06374aa9306122a778654b912f713 09:55:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:55:43 INFO - mozversion platform_version: 49.0a1 09:55:43 INFO - using testdate: 1463590543 09:55:43 INFO - actual date: 1463590543 09:55:43 INFO - starting webserver on 'localhost:49278' 09:55:43 INFO - SUITE-START | Running 2 tests 09:55:43 INFO - TEST-START | dromaeo_css 09:55:43 INFO - operating with platform_type : mac_ 09:55:43 INFO - Initialising browser for dromaeo_css test... 09:55:43 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpjI1sKB/profile http://localhost:49278/getInfo.html) 09:55:45 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 09:55:45 INFO - PROCESS | 1670 | colorDepth:24 09:55:45 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 09:55:45 INFO - PROCESS | 1670 | __metrics 09:55:45 INFO - PROCESS | 1670 | 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" 09:55:45 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:55:46 INFO - TEST-INFO | 1670: exit 0 09:55:46 INFO - Browser initialized. 09:55:46 INFO - Running cycle 1/1 for dromaeo_css test... 09:55:46 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpjI1sKB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:55:58 INFO - PROCESS | 1673 | RSS: Main: 238698496 09:55:58 INFO - PROCESS | 1673 | 09:56:49 INFO - PROCESS | 1673 | 1463590609445 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] 09:58:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 09:59:00 INFO - PROCESS | 1673 | RSS: Main: 347508736 09:59:00 INFO - PROCESS | 1673 | 10:02:03 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 10:02:04 INFO - PROCESS | 1673 | RSS: Main: 370651136 10:02:04 INFO - PROCESS | 1673 | 10:05:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 10:05:07 INFO - PROCESS | 1673 | RSS: Main: 372625408 10:05:07 INFO - PROCESS | 1673 | 10:08:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 10:08:10 INFO - PROCESS | 1673 | RSS: Main: 370135040 10:08:10 INFO - PROCESS | 1673 | 10:11:13 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 10:11:13 INFO - PROCESS | 1673 | RSS: Main: 337416192 10:11:13 INFO - PROCESS | 1673 | 10:14:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 10:14:17 INFO - PROCESS | 1673 | RSS: Main: 372543488 10:14:17 INFO - PROCESS | 1673 | 10:14:17 INFO - PROCESS | 1673 | __start_tp_report 10:14:17 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 10:14:17 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 10:14:17 INFO - PROCESS | 1673 | |i|pagename|runs| 10:14:17 INFO - PROCESS | 1673 | |0;dojo.html;3479.42589472737;3500.5274287971115;3521.242932769455;3531.9646803531955;3569.5894972078218;5727.770889164439;5762.481376676098;5804.941950580493;5876.177335173079;5888.470037696607;5782.624129431594;5855.590108692393;5858.7656493740305;5859.003969325215;5883.646981181132;5188.16989281715;5221.06020916235;5245.26566280721;5263.552598029172;5327.733613319338;4544.545545445462;5219.399769026572;5249.553787928031;5292.867678308035;5344.545713614348;5264;5325.174597937326;5326.813561525348;5336.626436149471;5343.251944727741;481.3273048659109;5265.0259701955265;5265.394479634852;5341.572674186055;5370.865728356803;4648.48866624671;5206.245094461282;5206.635535512516;5229.320188375525;5243.10867152585;5634.915476267859;5707.286589176353;5770.88458230833;5780.595358324919;5794.130880367937;6215.378462153793;6232.626042437469;6241.251049873998;6344.080108384304;6358.109864618957;5535.667859928417;6304.684765761739;6354.586951848115;6360.300366959631;6373.075903993895;5908.291005079375;5921.822345329604;5997.460228579449;6003.6097653652805;6004.969975150097;6295.905561416583;6326.620402775849;6399.424051835357;6455.9677201613695;6456.967715161394;6193.907091393633;6229.875402491924;6234.625922444668;6308.8422789429715;6322.841928951721;6244.687765611701;6357.237131544151;6367.808965731036;6368.5860419072615;6377.585456945284;6180.258368995749;6319.778807741707;6351.9682401588625;6364.904526432108;6370.0763389308295;6277.466415354746;6290.937090629127;6308.274548426898;6310.400511951358;6331.746730130743;4955.256711493233;4969.602431805447;4979.800807967641;4992.350994370781;4992.725400102957;4404.141192467502;4443.555644435531;4488.5287044859815;4491.505934347219;4510.548945105529;5794.0439827428;5852.209951656472;5855.882882342415;5864.208331875142;5877.0009098453365;6296.307406185316;6316.810495685137;6351.745930162834;6352.142460767737;6360.650164241011;5752.108423194411;5799;5831.475167234885;5858.004139296329;5901.380355062743;5480.177973304037;5504.559635229172;5506.5870059744875;5512.641678290898;5556.583256255797;6499.610023398516;6526.804195874131;6529.706163222666;6532.052852336385;6545.476361891133;6272.874542509216;6293.181886354744;6304.432601065926;6330.49356051524;6334.271558770616;6462.838429039311;6505.284418714033;6511.576747511396;6523.11937888379;6549.148610680581;6347.492200624031;6348.492120630246;6353.269374021862;6363.459105976044;6363.554551181558;6243.625382477066;6326.746930122744;6329.145565348803;6353.52348573868;6359.268684101387;6266.373362663697;6267.498600111889;6275.215598050244;6277.309495955533;6287.71705273273;5406.297181366397;6185.628862268279;6238.251409830849;6256.530760192913;6256.562040657111;4564.726116433025;5909.8227053188475;5954.434328738763;5961.4038596140035;6034.064719968412;6154.969225153845;6229.3770622939155;6257.374262573706;6279.215098112736;6287.402696743802;6016.458518733335;6146.907796382969;6173.444390004921;6175.938240617536;6179.783707570214 10:14:17 INFO - PROCESS | 1673 | |1;ext.html;530.6524226631557;533.0139242401558;548.7942021741846;556.5519756595938;556.604810584485;26132.738672613268;43086.35370469442;44532.66402007945;44557.440489582914;44574.77712611432;46033;46457.76771116148;46551.301730473984;46602.06795864081;46757;8721.869171962393;9886;10074.042965918226;10079.647212347569;10093.949530252376;8761.7809554761;11865.347405892673;12096.879031209708;12097.03223742103;12222.511099556052;12148.27110373376;12215.694607634836;12218.572349967753;12219.083568732336;12222.633321000385;8814.25078868297;11893;12048.819267710991;12112.454939527743;12174.147809653283;12051.819222711667;12127.272363658209;12143.514259429612;12166.939165304206;12175.08686848481;41820.79089604533;42284.154316913795;42338.78830605858;42343;42353;3296.324253528045;3323.8171900545462;3346.7489938254485;3351.078453425303;3385.5598772159706;29982;30213;30222.54666180009;30304.24239394011;30486.390272194654;2539.136693524211;2739.1645548107817;2753.559430491132;2782.0123856031223;2795.7483826455514;535140;537013;537796;538748.3062584662;539114.3044284793;36297.81851090727;67958.32041679618;68637;68773;68778.96831547531;37257.44113838294;38593.807030965225;38657;38658;38710.612893870704;15543.689126217638;15608.687826243411;15714.371425142866;15727.842721572866;15735.055896646238;11952.940235298767;12124.515019399301;12881;13036;13110.213387196798;7368.005319281829;8238.546879921661;8405.873911891327;8425.452345597516;8479.618417171243;3481.2689335239884;3481.70405515534;3488.7846217651972;3490.3717330880177;3516.8769093082133;884.1124199833401;923.5982347678781;928.6888892221046;1018.4042335233823;1112.6383925224172;30507.847460762998;30705.77176912943;30746.846265768527;30870;31103.84448077745;996.9800603988025;1094.7865166292497;1107.3224065540712;1179.9823002654966;1205.367182229319;11927;13805.86194138066;15734.9213253933;15758.842411575966;16164.676706465805;694540;696457;703485;703556;707015;35287.4706879397;45243.32135017977;45301.54698453039;45367;45421.77289113533;1334.9599512014654;1375.6217040313993;1463.8755705764704;1494.260341131147;1496.5360738171203;30105.397892042474;31381;31467.370652587277;31872.36255274928;32254.677453226104;54351.72824135854;54608.18087728767;55254.44745552493;55298;55298.72350638221;488342;565423;568618;569117;570463;22647.320580382613;23822.761772382055;24853.378665533506;28046.2988425291;28418.00536981196;44193.07006799072;47784.283235750794;48071.5192848067;48429.51570484391;49698;27691.861540692975;38653;38768.61231387763;39362.409563855996;39855;33432.8298509551;35283;38468;38968.80515597517;39586.40620390639;37922.24155516819;38411.80794096012;38669.64656237018;39332.606673932896;39758;33233.04375547909;33997.66002339945;34562;34834.9549513515;34939.65060349466;22021.559568808854;24800.131995380074;25118.874405627856;25425.575852271253;25796.484070318864 10:14:17 INFO - PROCESS | 1673 | |2;jquery.html;388.093800974724;391.5731852281013;394.2174783055636;395.69926855589745;414.9336106223005;20404.89797551012;20995.790042099576;21417.357479275608;21487.24794632188;21510.032048557812;18469.337759601633;20162.798372016274;20241;21898.01458934348;21909.561808763814;5092.847214583559;5407.053765591026;5411.053065713484;5430.945690543083;5433.864153396157;5139.511746384088;5454.7818087276655;5455.863603409926;5456.645318054334;5465.508104270615;5937.762489500415;5949.405059494059;5985.5810093293485;5987.970060149715;6001.159837622737;5868.559858010623;5958.851028724295;5965.3736357682255;5966.075258334965;6211.968940155315;6020.036794112964;6023.247094113236;6028.879422411571;6032.336442991267;6059.1820104286235;14617.342219600037;25650.743492565023;25985.480290394087;26240;26782.46435071307;2105.0236666782057;2174.4455163933253;2237.966430503544;2257.9661305080435;2367.135995361698;9838.409695418226;10094.13258547171;10420.114290285335;10453.84319235212;10457.52941117644;1582.4936020473453;1636;1688.1643586424675;1735.6181640039172;1778.3242367900245;105562;107814.92185078128;107976.4601176997;108178.45910770395;108815.45592272068;9540.90459095414;11729.648110556696;11846.637636671721;12104.213226140273;12300.507979680891;15223.010504317406;15342.616434589225;15364.769528457082;15368.769468457982;15369;8607.65569377218;8811.427257228259;8813.338999575057;8876.644934202688;8935.28517718581;8686.348523860735;8702.129787021373;8704.347173961978;8776.473411595325;8833.425827321204;4401.383806267125;4570.7257564546235;4626.6298696104905;4714.481407045222;4730.337752714623;1775.1922875091802;1957.8727382720078;1969.5666953270259;2003.3589251439691;2006.5284658105625;903.4850135422746;927.4759760735163;927.6753136402206;955.7992821507561;959.3811991265594;20070.59858802816;21028.474288142614;21114.15543378248;21208;21376.57246855054;935.2938531408879;959.822432849911;960.2126256469768;993.6174572789528;1026.6150193677372;10788.298760580537;11192.160587955774;11276.041536469327;11330.886691132982;11333.943330283295;586666;591741;593506.0324698349;619469;620186;93453.53273233591;123116;124994.37502812428;125110;131820;1003.6136087606178;1006.7281833904805;1022.4172221833633;1086.766345235778;1088.3524303039837;19187.424377268704;19635.509112272306;19652;19744.12907376716;19795.604087917873;44947.876303092686;50756.73108172325;51529.96940061253;55452;55747.72126139343;54845.72577137089;79710;82215;82561.58719206366;85148;7989.600519974094;8159.26566609008;8206.425550211667;8388.874166887623;8413.70552030676;11602.303861768321;12600.180988235736;12768.553100641433;12914.158885045275;13031.02267329935;10992.670219893416;11206.551737930391;11332.886671133183;11436.313821170756;11445.198836081394;8556.529390883561;10348.430836304076;10740;11095.11239100886;11168.274062185932;9388.812223755624;11210.943945280222;11303.378314192798;11403.087752979576;11590.304581725124;7492.288232617892;7891.368690504861;8192.918070819216;8313.210245026712;8367.790805229917;10892.891071089505;11002.119830413387;11021.118310535297;11080.944595276973;11259.211855170059 10:14:17 INFO - PROCESS | 1673 | |3;mootools.html;1449.3550370085309;1454.7599646058402;1590.9045457272564;1838.1911958738153;2017.8420771492622;8234.670613175473;9065.456072635647;9400.153986141246;9400.24798016158;9453.385529940566;8944.329175311845;9177.357584969055;9179.31155163362;9192.31057670674;9196;2226.866388016716;2466.5683505386573;2507.623856421533;2548.4011257354505;2550.2731721459386;2519.143491212987;2541.0598078710905;2543.55487789637;2544.7709706126443;2548.388386787167;2496.4133428644254;2550.2094350751236;2551.8468891866455;2552.923412297634;2553.5658937980493;2180.5311857950537;2409.2772168349425;2473.13440295895;2473.567125753004;2494.7006359236916;2507.260358194319;2522.2937577478338;2542.7965762739123;2545.6563363945816;2546.59254519276;9087.636494540211;9263.768405789842;9265.490398028172;9270.397424167408;9278.675246366345;4682.531746825324;5639.802606908779;5656.1798539211995;5662.348829884575;5666.575006874501;3611.9886431799137;3622.9275414491563;3625.6736893679695;3715.089802998256;3724.1806802503406;1030.6598822388669;1056.9418681972488;1057.9682609521726;1059.56557811297;1061.3207547169818;12453.439595218146;12477.188982716094;12487.31319777412;12501.312427816467;12503;4969.204927211701;4981.875453113628;5095.414027386824;5098.643094983389;5111.182210846247;10372.533236004398;10418.843717344245;10419.010194031554;10422;10432.478376081317;5606.103023516218;5619.578531610146;5620.718964051781;5621.943780562224;5629.774809007594;4636.049609829976;4801.303810947393;4983.5763960063805;5099.923501147486;5120.231965205175;3469.5836499619704;3956.6564068705457;4155.709100362946;4211.810468528924;4236.724612900151;1381.0815807487909;1403.684171061503;1407.859214078584;1408.7886816977536;1420.31824724133;917.5824999625155;926.9258459323238;939.8778158839307;955.8279509688323;973.776031512756;6014.218151640259;6050.48570871472;6066.817995460143;6145.84635384119;6152.541527410639;465.42752414530315;476.57346674725926;481.059528621544;482.59944246275506;488.94866039066096;3995.20095980805;4038.1116154446563;4101.302778527657;4107.774072425986;4121.5878412158545;10895.455227238606;10908.236423450282;11351.829722554168;11360.659180224606;11367;11221.270617409842;11352.772944541062;11352.94323528377;11365.977062064454;11374.31754094757;8515.19105684959;8518.190771876662;8521.914780852112;8578.18507241811;8619.655213791504;5655.971720141538;5945.464908158114;5998.130271110752;6020.4280593343565;6035.78874739382;10335.276530643083;10475.266731328733;10493.370397776158;10507.474626268504;10551.103156231806;10264.692059238234;10618.52216650268;10647.414392208502;10774.676759697222;10781.407022613832;6864.313568643096;7101.609411482418;7307.744228951961;7469.215732348025;7546.2831031051965;8623.827523449621;8755.824883502422;8836.293096552132;8852.070532593985;8853.335999800169;7610.315071643578;7815.570143641927;7824.765257042298;7880.290773830383;7955.1249362568915;7560.243975602396;7639.465237433326;7650.273224043707;7798.33714134305;7806.0632724073475;7215.747448839265;7271.854562908607;7304.415646748141;7305.2329505403295;7311.086114235721;4818.975905120452;5011.298418221525;5098.2352647103235;5129.204973229155;5142.254373115952;5059.569936555434;5094.719790411563;5149.279100925798;5259.289995850511;5376.381716102698 10:14:17 INFO - PROCESS | 1673 | |4;prototype.html;310.0404248849809;313.2278932431556;326.7908538535337;329.81365528476397;330.12846086332087;4719.952800471994;5228.369553368807;5349.22436246744;5406.810761623344;5438.401775804658;5343.812966546172;5355.919661205085;5377.784888604451;5379.488948549892;5385.569154467643;3620.167361506855;3948.545917219528;4115.464989551354;4119.732217405859;4134.400511925769;3130.2174456385906;3439.5725773803842;4020.2562525932794;4135.31767258402;4154.916901661965;3843.8852732707483;3969.027588240885;4003.7188099808072;4018.5378681451716;4043.4541336919433;3452.58568971725;4003.4386589230226;4020.658244049245;4026.1947610478023;4039.9596004040172;3096.5200393938976;3523.5243242162237;3542.8919792723286;3547.733919956014;3570.268419816387;4368.929612244989;4709.317149013408;4888.315635810989;5123.077845987721;5134.563562097226;3997.860609726243;4052.7365721228025;4617.861464156081;4629.8148074077335;4639.8144074237325;5462.289902312714;5703.372629010805;5728.68492232927;5735.6197178171005;5750.281214848144;3477.565304336958;3842.654161125512;3844.7500912440883;3923.9215215695526;3941.2905676978135;4977.352944117278;5611.66330020196;5633.380328163898;5722.341930677942;5964.791232306892;3247.902562923116;3373.241020770307;3430.9313813723584;3981.6416522513114;3990.184466067932;3506.2111025019676;3804.619538046173;3870.9806450967567;3888.6111388861455;4046.1503084352025;3978;4667.766611669403;4684.81260749573;4705.882352941204;4711.411073615798;4888.682235654671;5038.874028149252;5112.897742045213;5311.415744268127;5767.653940763567;1091.3888222595372;1321.3525372567374;1369.205860600849;1374.3540535948089;1378.8896888248917;763.2215140556601;816.7958010497375;825.3974598543098;843.215809297347;847.894013248344;4063.8824323310737;4578.649298456963;4581.450225972904;4615.26155815068;4644.674872758875;4910.754462276872;4941.160002799533;5024.773885175176;5042.899142017139;5082.567981721595;3816.866409675648;4043.838246470167;4089.7137200395687;4433.958019865329;4534.88895220673;2303.504746479515;2623.5500317082974;2683.4633073385403;2928.2137895883493;3133.918798014681;5493.395726470085;5698.1452782083015;5730.312362516442;6140.508759299245;6191;5799.3040835100055;6093.512518998469;6140.078988151724;6151.2618485781095;6173.506119510428;5195.948040519546;5657.67044744493;6144.109104179958;6175.62946223228;6176.289726681399;4879.707217566815;5397.757100930546;5916.171735957056;5964.194833697396;6010.879782404415;5235.3717553893775;5535.225068490495;5933.465988061095;5945.702714864153;5970.194023806731;6136.8772624548155;6250.218722659668;6254.6247225166635;6257.4681152102585;6304.148939893239;3418.4701371287492;3884.368565202565;3927.429028388553;3999.92000160001;4037.6567991719858;5633.521150702236;5915.526757859275;5932.851678708067;5985.940140598538;5998.25021872266;5323.71080435401;5823.213866128188;5827.125931110368;5828.610269789366;5831;5500.882160368207;5799;5799.82600521985;5817.156512305776;5862.794802181903;4625.444946606429;4759.619230461485;5189.870253243699;5212.348456442945;5505.559555235492;5784.913226301524;5827.271591051119;5831.912521312099;5842.737076831468;5845.678487683218;5204.8178313758835;5382.255049924488;5600.851825375869;5690.886182276414;5707.8858422832145 10:14:17 INFO - PROCESS | 1673 | |5;yui.html;626.6522080245463;693.4591019005178;707.3633729643324;744.9255074492552;759.828865592301;15049.322780474877;15454.845451545481;15555.066695998248;15575.06549607023;15811.60470988223;14713;14809.629759255997;14812.963092583523;14829.92585037076;14845.92577037116;725.405167762435;748.9400847932179;888.449161519857;916.1754421021068;917.3853518142861;894.3471265975842;920.9907900921005;924.117467818232;924.1313165624325;931.1433481197281;957.4686049242667;971.8590804333368;972.377678285894;975.0444564326964;978.1148061004767;759.2066290726204;782.6830133795822;783.6316931042458;966.4925913895191;968.9660861869871;687.6458623808743;708.9858202835915;710.499098135813;710.5523520182252;711.3029231353252;15343.46297879569;15408.614784630361;15409.306581203873;15419.22903854814;15480.225988700633;12311.19977201479;12366.876331236663;12448.937755311166;12641.62075137738;12697;10783.83824242637;10933.289336193122;11014.889851101547;11230.438478076063;11399.48702308398;4008.2985477541315;4039.858604948842;4057.594240575919;4090.9795451022555;4250.574942505756;73489;75685.62157189178;88092.55953720254;88226.67659985105;88484;9643.614255429844;9686.515674216262;9698.418094914328;9777.168940639984;10289.742756431;22688.071513921503;22934;22954.311370658907;22991.885040575024;22996.96513656856;6561.507886908501;7649.961750191324;7763.9611801941755;7829.843403131906;7850.332721718603;4025.476688030596;4070.7964601769795;4109.321961876257;4125.64931980779;4353.717008394444;3607.4408466687705;3658.9451158232646;3695.4826324314618;3698.1863989922176;3722.274156539449;670.2359310386164;673.8820706376463;673.9427148692318;675.2201207605224;679.9524033317621;550.3313474128953;565.6379916853217;568.5309619563876;598.2567353407255;609.5702529716539;7948.920510794818;8028;8162.551059691774;8212.794680133044;8345.332373410112;505.45663411832425;580.494969376645;581.7847396463336;600.2376981233896;602.3494625804121;3786.263168051319;3840.9039774005314;3846.8845934622004;3926.5680775114715;4088.9182216355507;88488.55755721309;90783;91383;91564;95824;56758.92879427026;77949;78144.21855781483;81917;87282.1271787274;53309.466905332;74124.88812667814;74976.62511687407;75336;75729.62135189399;7404.333609975128;7409.481336306622;7583.317501424898;7654.387648988179;7828.099768526692;45670.771646141555;46607.834804130165;46801.297980531;47049.29426058543;48864.75567622139;63792.3620763805;67107;67229.32770672231;67239;67482.66258668675;10390;10425.635102771366;10601.522931468257;10673;10878.564857405932;10983.725406864573;11081.445927703422;11157.72105697364;11239.426480292928;11305.604303849326;10517.00088491592;10545.57817687284;10783;10823.350598964087;10831.620893268699;10418.322809017389;10445.112165465716;10627.787444251226;10739.516721747385;10826.67519974402;10030.498475076363;10069.647562335284;10200.4389758564;10291.691249262534;10570.630027948984;8734.82530349402;8752.427376847718;9056.36605437613;9304.767380815534;9548;8365.079841217339;8533.061363249913;8647.956760216159;8681.435706679045;8979 10:14:17 INFO - PROCESS | 1673 | __end_tp_report 10:14:17 INFO - PROCESS | 1673 | __start_cc_report 10:14:17 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1180 10:14:17 INFO - PROCESS | 1673 | __end_cc_report 10:14:17 INFO - PROCESS | 1673 | __startTimestamp1463591657494__endTimestamp 10:14:17 INFO - PROCESS | 1673 | 10:14:17 INFO - PROCESS | 1673 | ------- Summary: start ------- 10:14:17 INFO - PROCESS | 1673 | Number of tests: 6 10:14:17 INFO - PROCESS | 1673 | 10:14:17 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5803.31 Median:6034.06 stddev:787.26 (13.0%) stddev-sans-first:768.28 10:14:17 INFO - PROCESS | 1673 | Values: 3479.4 3500.5 3521.2 3532.0 3569.6 5727.8 5762.5 5804.9 5876.2 5888.5 5782.6 5855.6 5858.8 5859.0 5883.6 5188.2 5221.1 5245.3 5263.6 5327.7 4544.5 5219.4 5249.6 5292.9 5344.5 5264.0 5325.2 5326.8 5336.6 5343.3 481.3 5265.0 5265.4 5341.6 5370.9 4648.5 5206.2 5206.6 5229.3 5243.1 5634.9 5707.3 5770.9 5780.6 5794.1 6215.4 6232.6 6241.3 6344.1 6358.1 5535.7 6304.7 6354.6 6360.3 6373.1 5908.3 5921.8 5997.5 6003.6 6005.0 6295.9 6326.6 6399.4 6456.0 6457.0 6193.9 6229.9 6234.6 6308.8 6322.8 6244.7 6357.2 6367.8 6368.6 6377.6 6180.3 6319.8 6352.0 6364.9 6370.1 6277.5 6290.9 6308.3 6310.4 6331.7 4955.3 4969.6 4979.8 4992.4 4992.7 4404.1 4443.6 4488.5 4491.5 4510.5 5794.0 5852.2 5855.9 5864.2 5877.0 6296.3 6316.8 6351.7 6352.1 6360.7 5752.1 5799.0 5831.5 5858.0 5901.4 5480.2 5504.6 5506.6 5512.6 5556.6 6499.6 6526.8 6529.7 6532.1 6545.5 6272.9 6293.2 6304.4 6330.5 6334.3 6462.8 6505.3 6511.6 6523.1 6549.1 6347.5 6348.5 6353.3 6363.5 6363.6 6243.6 6326.7 6329.1 6353.5 6359.3 6266.4 6267.5 6275.2 6277.3 6287.7 5406.3 6185.6 6238.3 6256.5 6256.6 4564.7 5909.8 5954.4 5961.4 6034.1 6155.0 6229.4 6257.4 6279.2 6287.4 6016.5 6146.9 6173.4 6175.9 6179.8 10:14:17 INFO - PROCESS | 1673 | 10:14:17 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:71614.32 Median:19161.87 stddev:161281.73 (841.7%) stddev-sans-first:161645.86 10:14:17 INFO - PROCESS | 1673 | Values: 530.7 533.0 548.8 556.6 556.6 26132.7 43086.4 44532.7 44557.4 44574.8 46033.0 46457.8 46551.3 46602.1 46757.0 8721.9 9886.0 10074.0 10079.6 10093.9 8761.8 11865.3 12096.9 12097.0 12222.5 12148.3 12215.7 12218.6 12219.1 12222.6 8814.3 11893.0 12048.8 12112.5 12174.1 12051.8 12127.3 12143.5 12166.9 12175.1 41820.8 42284.2 42338.8 42343.0 42353.0 3296.3 3323.8 3346.7 3351.1 3385.6 29982.0 30213.0 30222.5 30304.2 30486.4 2539.1 2739.2 2753.6 2782.0 2795.7 535140.0 537013.0 537796.0 538748.3 539114.3 36297.8 67958.3 68637.0 68773.0 68779.0 37257.4 38593.8 38657.0 38658.0 38710.6 15543.7 15608.7 15714.4 15727.8 15735.1 11952.9 12124.5 12881.0 13036.0 13110.2 7368.0 8238.5 8405.9 8425.5 8479.6 3481.3 3481.7 3488.8 3490.4 3516.9 884.1 923.6 928.7 1018.4 1112.6 30507.8 30705.8 30746.8 30870.0 31103.8 997.0 1094.8 1107.3 1180.0 1205.4 11927.0 13805.9 15734.9 15758.8 16164.7 694540.0 696457.0 703485.0 703556.0 707015.0 35287.5 45243.3 45301.5 45367.0 45421.8 1335.0 1375.6 1463.9 1494.3 1496.5 30105.4 31381.0 31467.4 31872.4 32254.7 54351.7 54608.2 55254.4 55298.0 55298.7 488342.0 565423.0 568618.0 569117.0 570463.0 22647.3 23822.8 24853.4 28046.3 28418.0 44193.1 47784.3 48071.5 48429.5 49698.0 27691.9 38653.0 38768.6 39362.4 39855.0 33432.8 35283.0 38468.0 38968.8 39586.4 37922.2 38411.8 38669.6 39332.6 39758.0 33233.0 33997.7 34562.0 34835.0 34939.7 22021.6 24800.1 25118.9 25425.6 25796.5 10:14:17 INFO - PROCESS | 1673 | 10:14:17 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:34592.46 Median:2312.55 stddev:100104.76 (4328.8%) stddev-sans-first:100352.63 10:14:17 INFO - PROCESS | 1673 | Values: 388.1 391.6 394.2 395.7 414.9 20404.9 20995.8 21417.4 21487.2 21510.0 18469.3 20162.8 20241.0 21898.0 21909.6 5092.8 5407.1 5411.1 5430.9 5433.9 5139.5 5454.8 5455.9 5456.6 5465.5 5937.8 5949.4 5985.6 5988.0 6001.2 5868.6 5958.9 5965.4 5966.1 6212.0 6020.0 6023.2 6028.9 6032.3 6059.2 14617.3 25650.7 25985.5 26240.0 26782.5 2105.0 2174.4 2238.0 2258.0 2367.1 9838.4 10094.1 10420.1 10453.8 10457.5 1582.5 1636.0 1688.2 1735.6 1778.3 105562.0 107814.9 107976.5 108178.5 108815.5 9540.9 11729.6 11846.6 12104.2 12300.5 15223.0 15342.6 15364.8 15368.8 15369.0 8607.7 8811.4 8813.3 8876.6 8935.3 8686.3 8702.1 8704.3 8776.5 8833.4 4401.4 4570.7 4626.6 4714.5 4730.3 1775.2 1957.9 1969.6 2003.4 2006.5 903.5 927.5 927.7 955.8 959.4 20070.6 21028.5 21114.2 21208.0 21376.6 935.3 959.8 960.2 993.6 1026.6 10788.3 11192.2 11276.0 11330.9 11333.9 586666.0 591741.0 593506.0 619469.0 620186.0 93453.5 123116.0 124994.4 125110.0 131820.0 1003.6 1006.7 1022.4 1086.8 1088.4 19187.4 19635.5 19652.0 19744.1 19795.6 44947.9 50756.7 51530.0 55452.0 55747.7 54845.7 79710.0 82215.0 82561.6 85148.0 7989.6 8159.3 8206.4 8388.9 8413.7 11602.3 12600.2 12768.6 12914.2 13031.0 10992.7 11206.6 11332.9 11436.3 11445.2 8556.5 10348.4 10740.0 11095.1 11168.3 9388.8 11210.9 11303.4 11403.1 11590.3 7492.3 7891.4 8192.9 8313.2 8367.8 10892.9 11002.1 11021.1 11080.9 11259.2 10:14:17 INFO - PROCESS | 1673 | 10:14:17 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5956.53 Median:2650.02 stddev:3390.38 (127.9%) stddev-sans-first:3382.97 10:14:17 INFO - PROCESS | 1673 | Values: 1449.4 1454.8 1590.9 1838.2 2017.8 8234.7 9065.5 9400.2 9400.2 9453.4 8944.3 9177.4 9179.3 9192.3 9196.0 2226.9 2466.6 2507.6 2548.4 2550.3 2519.1 2541.1 2543.6 2544.8 2548.4 2496.4 2550.2 2551.8 2552.9 2553.6 2180.5 2409.3 2473.1 2473.6 2494.7 2507.3 2522.3 2542.8 2545.7 2546.6 9087.6 9263.8 9265.5 9270.4 9278.7 4682.5 5639.8 5656.2 5662.3 5666.6 3612.0 3622.9 3625.7 3715.1 3724.2 1030.7 1056.9 1058.0 1059.6 1061.3 12453.4 12477.2 12487.3 12501.3 12503.0 4969.2 4981.9 5095.4 5098.6 5111.2 10372.5 10418.8 10419.0 10422.0 10432.5 5606.1 5619.6 5620.7 5621.9 5629.8 4636.0 4801.3 4983.6 5099.9 5120.2 3469.6 3956.7 4155.7 4211.8 4236.7 1381.1 1403.7 1407.9 1408.8 1420.3 917.6 926.9 939.9 955.8 973.8 6014.2 6050.5 6066.8 6145.8 6152.5 465.4 476.6 481.1 482.6 488.9 3995.2 4038.1 4101.3 4107.8 4121.6 10895.5 10908.2 11351.8 11360.7 11367.0 11221.3 11352.8 11352.9 11366.0 11374.3 8515.2 8518.2 8521.9 8578.2 8619.7 5656.0 5945.5 5998.1 6020.4 6035.8 10335.3 10475.3 10493.4 10507.5 10551.1 10264.7 10618.5 10647.4 10774.7 10781.4 6864.3 7101.6 7307.7 7469.2 7546.3 8623.8 8755.8 8836.3 8852.1 8853.3 7610.3 7815.6 7824.8 7880.3 7955.1 7560.2 7639.5 7650.3 7798.3 7806.1 7215.7 7271.9 7304.4 7305.2 7311.1 4819.0 5011.3 5098.2 5129.2 5142.3 5059.6 5094.7 5149.3 5259.3 5376.4 10:14:17 INFO - PROCESS | 1673 | 10:14:17 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:4530.16 Median:5001.06 stddev:1456.08 (29.1%) stddev-sans-first:1425.30 10:14:17 INFO - PROCESS | 1673 | Values: 310.0 313.2 326.8 329.8 330.1 4720.0 5228.4 5349.2 5406.8 5438.4 5343.8 5355.9 5377.8 5379.5 5385.6 3620.2 3948.5 4115.5 4119.7 4134.4 3130.2 3439.6 4020.3 4135.3 4154.9 3843.9 3969.0 4003.7 4018.5 4043.5 3452.6 4003.4 4020.7 4026.2 4040.0 3096.5 3523.5 3542.9 3547.7 3570.3 4368.9 4709.3 4888.3 5123.1 5134.6 3997.9 4052.7 4617.9 4629.8 4639.8 5462.3 5703.4 5728.7 5735.6 5750.3 3477.6 3842.7 3844.8 3923.9 3941.3 4977.4 5611.7 5633.4 5722.3 5964.8 3247.9 3373.2 3430.9 3981.6 3990.2 3506.2 3804.6 3871.0 3888.6 4046.2 3978.0 4667.8 4684.8 4705.9 4711.4 4888.7 5038.9 5112.9 5311.4 5767.7 1091.4 1321.4 1369.2 1374.4 1378.9 763.2 816.8 825.4 843.2 847.9 4063.9 4578.6 4581.5 4615.3 4644.7 4910.8 4941.2 5024.8 5042.9 5082.6 3816.9 4043.8 4089.7 4434.0 4534.9 2303.5 2623.6 2683.5 2928.2 3133.9 5493.4 5698.1 5730.3 6140.5 6191.0 5799.3 6093.5 6140.1 6151.3 6173.5 5195.9 5657.7 6144.1 6175.6 6176.3 4879.7 5397.8 5916.2 5964.2 6010.9 5235.4 5535.2 5933.5 5945.7 5970.2 6136.9 6250.2 6254.6 6257.5 6304.1 3418.5 3884.4 3927.4 3999.9 4037.7 5633.5 5915.5 5932.9 5985.9 5998.3 5323.7 5823.2 5827.1 5828.6 5831.0 5500.9 5799.0 5799.8 5817.2 5862.8 4625.4 4759.6 5189.9 5212.3 5505.6 5784.9 5827.3 5831.9 5842.7 5845.7 5204.8 5382.3 5600.9 5690.9 5707.9 10:14:17 INFO - PROCESS | 1673 | 10:14:17 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:18165.56 Median:601.29 stddev:25704.43 (4274.9%) stddev-sans-first:25742.80 10:14:17 INFO - PROCESS | 1673 | Values: 626.7 693.5 707.4 744.9 759.8 15049.3 15454.8 15555.1 15575.1 15811.6 14713.0 14809.6 14813.0 14829.9 14845.9 725.4 748.9 888.4 916.2 917.4 894.3 921.0 924.1 924.1 931.1 957.5 971.9 972.4 975.0 978.1 759.2 782.7 783.6 966.5 969.0 687.6 709.0 710.5 710.6 711.3 15343.5 15408.6 15409.3 15419.2 15480.2 12311.2 12366.9 12448.9 12641.6 12697.0 10783.8 10933.3 11014.9 11230.4 11399.5 4008.3 4039.9 4057.6 4091.0 4250.6 73489.0 75685.6 88092.6 88226.7 88484.0 9643.6 9686.5 9698.4 9777.2 10289.7 22688.1 22934.0 22954.3 22991.9 22997.0 6561.5 7650.0 7764.0 7829.8 7850.3 4025.5 4070.8 4109.3 4125.6 4353.7 3607.4 3658.9 3695.5 3698.2 3722.3 670.2 673.9 673.9 675.2 680.0 550.3 565.6 568.5 598.3 609.6 7948.9 8028.0 8162.6 8212.8 8345.3 505.5 580.5 581.8 600.2 602.3 3786.3 3840.9 3846.9 3926.6 4088.9 88488.6 90783.0 91383.0 91564.0 95824.0 56758.9 77949.0 78144.2 81917.0 87282.1 53309.5 74124.9 74976.6 75336.0 75729.6 7404.3 7409.5 7583.3 7654.4 7828.1 45670.8 46607.8 46801.3 47049.3 48864.8 63792.4 67107.0 67229.3 67239.0 67482.7 10390.0 10425.6 10601.5 10673.0 10878.6 10983.7 11081.4 11157.7 11239.4 11305.6 10517.0 10545.6 10783.0 10823.4 10831.6 10418.3 10445.1 10627.8 10739.5 10826.7 10030.5 10069.6 10200.4 10291.7 10570.6 8734.8 8752.4 9056.4 9304.8 9548.0 8365.1 8533.1 8648.0 8681.4 8979.0 10:14:17 INFO - PROCESS | 1673 | -------- Summary: end -------- 10:14:17 INFO - PROCESS | 1673 | 10:14:18 INFO - TEST-INFO | 1673: exit 0 10:14:18 INFO - TEST-OK | dromaeo_css | took 1114743ms 10:14:18 INFO - TEST-START | kraken 10:14:18 INFO - operating with platform_type : mac_ 10:14:18 INFO - Initialising browser for kraken test... 10:14:18 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp1E9Q1b/profile http://localhost:49278/getInfo.html) 10:14:20 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 10:14:20 INFO - PROCESS | 1678 | colorDepth:24 10:14:20 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 10:14:20 INFO - PROCESS | 1678 | __metrics 10:14:20 INFO - PROCESS | 1678 | 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" 10:14:20 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:14:21 INFO - TEST-INFO | 1678: exit 0 10:14:21 INFO - Browser initialized. 10:14:21 INFO - Running cycle 1/1 for kraken test... 10:14:21 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp1E9Q1b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:14:33 INFO - PROCESS | 1680 | RSS: Main: 238505984 10:14:33 INFO - PROCESS | 1680 | 10:14:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 10:14:37 INFO - PROCESS | 1680 | RSS: Main: 286744576 10:14:37 INFO - PROCESS | 1680 | 10:14:39 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 10:14:40 INFO - PROCESS | 1680 | RSS: Main: 295985152 10:14:40 INFO - PROCESS | 1680 | 10:14:43 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 10:14:44 INFO - PROCESS | 1680 | RSS: Main: 385908736 10:14:44 INFO - PROCESS | 1680 | 10:14:46 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 10:14:47 INFO - PROCESS | 1680 | RSS: Main: 285151232 10:14:47 INFO - PROCESS | 1680 | 10:14:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:14:50 INFO - PROCESS | 1680 | RSS: Main: 289296384 10:14:50 INFO - PROCESS | 1680 | 10:14:52 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 10:14:53 INFO - PROCESS | 1680 | RSS: Main: 325578752 10:14:53 INFO - PROCESS | 1680 | 10:14:56 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 10:14:57 INFO - PROCESS | 1680 | RSS: Main: 330649600 10:14:57 INFO - PROCESS | 1680 | 10:15:00 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 10:15:00 INFO - PROCESS | 1680 | RSS: Main: 328822784 10:15:00 INFO - PROCESS | 1680 | 10:15:02 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:15:03 INFO - PROCESS | 1680 | RSS: Main: 315465728 10:15:03 INFO - PROCESS | 1680 | 10:15:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:15:06 INFO - PROCESS | 1680 | RSS: Main: 322797568 10:15:06 INFO - PROCESS | 1680 | 10:15:08 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:15:08 INFO - PROCESS | 1680 | RSS: Main: 324038656 10:15:08 INFO - PROCESS | 1680 | 10:15:11 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:15:12 INFO - PROCESS | 1680 | RSS: Main: 328835072 10:15:12 INFO - PROCESS | 1680 | 10:15:15 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:15:16 INFO - PROCESS | 1680 | RSS: Main: 326512640 10:15:16 INFO - PROCESS | 1680 | 10:15:17 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 10:15:18 INFO - PROCESS | 1680 | RSS: Main: 373850112 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | __start_tp_report 10:15:18 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 10:15:18 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 10:15:18 INFO - PROCESS | 1680 | |i|pagename|runs| 10:15:18 INFO - PROCESS | 1680 | |0;ai-astar;111;105;111;111;111;110;111;111;111;112 10:15:18 INFO - PROCESS | 1680 | |1;audio-beat-detection;156;116;140;197;116;137;135;118;168;115 10:15:18 INFO - PROCESS | 1680 | |2;audio-dft;182;185;186;185;183;183;184;184;181;184 10:15:18 INFO - PROCESS | 1680 | |3;audio-fft;77;71;57;124;56;62;57;58;111;55 10:15:18 INFO - PROCESS | 1680 | |4;audio-oscillator;89;100;133;80;79;76;101;81;82;101 10:15:18 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;86;84;86;84;86;84;89;126;86;83 10:15:18 INFO - PROCESS | 1680 | |6;imaging-darkroom;91;93;98;93;92;92;94;93;92;93 10:15:18 INFO - PROCESS | 1680 | |7;imaging-desaturate;85;86;85;86;86;85;85;84;86;100 10:15:18 INFO - PROCESS | 1680 | |8;json-parse-financial;57;57;59;58;55;58;56;115;55;56 10:15:18 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;64;63;78;75;71;69;69;92;99;99 10:15:18 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;86;84;85;84;82;85;84;85;85;87 10:15:18 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;138;132;135;135;134;135;134;134;215;139 10:15:18 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;162;218;158;195;228;144;197;139;184;219 10:15:18 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;65;65;67;66;66;64;67;64;64;65 10:15:18 INFO - PROCESS | 1680 | __end_tp_report 10:15:18 INFO - PROCESS | 1680 | __start_cc_report 10:15:18 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,2285 10:15:18 INFO - PROCESS | 1680 | __end_cc_report 10:15:18 INFO - PROCESS | 1680 | __startTimestamp1463591718195__endTimestamp 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | ------- Summary: start ------- 10:15:18 INFO - PROCESS | 1680 | Number of tests: 14 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:1.96 (1.8%) stddev-sans-first:2.06 10:15:18 INFO - PROCESS | 1680 | Values: 111.0 105.0 111.0 111.0 111.0 110.0 111.0 111.0 111.0 112.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:138.50 stddev:27.05 (19.5%) stddev-sans-first:28.04 10:15:18 INFO - PROCESS | 1680 | Values: 156.0 116.0 140.0 197.0 116.0 137.0 135.0 118.0 168.0 115.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.49 (0.8%) stddev-sans-first:1.45 10:15:18 INFO - PROCESS | 1680 | Values: 182.0 185.0 186.0 185.0 183.0 183.0 184.0 184.0 181.0 184.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:72.80 Median:57.50 stddev:24.80 (43.1%) stddev-sans-first:26.26 10:15:18 INFO - PROCESS | 1680 | Values: 77.0 71.0 57.0 124.0 56.0 62.0 57.0 58.0 111.0 55.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:79.50 stddev:17.34 (21.8%) stddev-sans-first:18.35 10:15:18 INFO - PROCESS | 1680 | Values: 89.0 100.0 133.0 80.0 79.0 76.0 101.0 81.0 82.0 101.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:86.00 stddev:12.97 (15.1%) stddev-sans-first:13.70 10:15:18 INFO - PROCESS | 1680 | Values: 86.0 84.0 86.0 84.0 86.0 84.0 89.0 126.0 86.0 83.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:1.91 (2.1%) stddev-sans-first:1.87 10:15:18 INFO - PROCESS | 1680 | Values: 91.0 93.0 98.0 93.0 92.0 92.0 94.0 93.0 92.0 93.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:86.80 Median:85.50 stddev:4.69 (5.5%) stddev-sans-first:4.92 10:15:18 INFO - PROCESS | 1680 | Values: 85.0 86.0 85.0 86.0 86.0 85.0 85.0 84.0 86.0 100.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:57.00 stddev:18.46 (32.4%) stddev-sans-first:19.47 10:15:18 INFO - PROCESS | 1680 | Values: 57.0 57.0 59.0 58.0 55.0 58.0 56.0 115.0 55.0 56.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:77.90 Median:76.50 stddev:13.82 (18.1%) stddev-sans-first:13.71 10:15:18 INFO - PROCESS | 1680 | Values: 64.0 63.0 78.0 75.0 71.0 69.0 69.0 92.0 99.0 99.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.33 10:15:18 INFO - PROCESS | 1680 | Values: 86.0 84.0 85.0 84.0 82.0 85.0 84.0 85.0 85.0 87.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:135.00 stddev:25.34 (18.8%) stddev-sans-first:26.81 10:15:18 INFO - PROCESS | 1680 | Values: 138.0 132.0 135.0 135.0 134.0 135.0 134.0 134.0 215.0 139.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.40 Median:196.00 stddev:32.30 (16.5%) stddev-sans-first:33.23 10:15:18 INFO - PROCESS | 1680 | Values: 162.0 218.0 158.0 195.0 228.0 144.0 197.0 139.0 184.0 219.0 10:15:18 INFO - PROCESS | 1680 | 10:15:18 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:1.16 (1.8%) stddev-sans-first:1.22 10:15:18 INFO - PROCESS | 1680 | Values: 65.0 65.0 67.0 66.0 66.0 64.0 67.0 64.0 64.0 65.0 10:15:18 INFO - PROCESS | 1680 | -------- Summary: end -------- 10:15:18 INFO - PROCESS | 1680 | 10:15:19 INFO - TEST-INFO | 1680: exit 0 10:15:19 INFO - TEST-OK | kraken | took 61073ms 10:15:19 INFO - SUITE-END | took 1175s 10:15:19 INFO - Completed test suite (00:19:36) 10:15:19 INFO - javascript benchmark 10:15:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3479.42589472737, 3500.5274287971115, 3521.242932769455, 3531.9646803531955, 3569.5894972078218, 5727.770889164439, 5762.481376676098, 5804.941950580493, 5876.177335173079, 5888.470037696607, 5782.624129431594, 5855.590108692393, 5858.7656493740305, 5859.003969325215, 5883.646981181132, 5188.16989281715, 5221.06020916235, 5245.26566280721, 5263.552598029172, 5327.733613319338, 4544.545545445462, 5219.399769026572, 5249.553787928031, 5292.867678308035, 5344.545713614348, 5264.0, 5325.174597937326, 5326.813561525348, 5336.626436149471, 5343.251944727741, 481.3273048659109, 5265.0259701955265, 5265.394479634852, 5341.572674186055, 5370.865728356803, 4648.48866624671, 5206.245094461282, 5206.635535512516, 5229.320188375525, 5243.10867152585, 5634.915476267859, 5707.286589176353, 5770.88458230833, 5780.595358324919, 5794.130880367937, 6215.378462153793, 6232.626042437469, 6241.251049873998, 6344.080108384304, 6358.109864618957, 5535.667859928417, 6304.684765761739, 6354.586951848115, 6360.300366959631, 6373.075903993895, 5908.291005079375, 5921.822345329604, 5997.460228579449, 6003.6097653652805, 6004.969975150097, 6295.905561416583, 6326.620402775849, 6399.424051835357, 6455.9677201613695, 6456.967715161394, 6193.907091393633, 6229.875402491924, 6234.625922444668, 6308.8422789429715, 6322.841928951721, 6244.687765611701, 6357.237131544151, 6367.808965731036, 6368.5860419072615, 6377.585456945284, 6180.258368995749, 6319.778807741707, 6351.9682401588625, 6364.904526432108, 6370.0763389308295, 6277.466415354746, 6290.937090629127, 6308.274548426898, 6310.400511951358, 6331.746730130743, 4955.256711493233, 4969.602431805447, 4979.800807967641, 4992.350994370781, 4992.725400102957, 4404.141192467502, 4443.555644435531, 4488.5287044859815, 4491.505934347219, 4510.548945105529, 5794.0439827428, 5852.209951656472, 5855.882882342415, 5864.208331875142, 5877.0009098453365, 6296.307406185316, 6316.810495685137, 6351.745930162834, 6352.142460767737, 6360.650164241011, 5752.108423194411, 5799.0, 5831.475167234885, 5858.004139296329, 5901.380355062743, 5480.177973304037, 5504.559635229172, 5506.5870059744875, 5512.641678290898, 5556.583256255797, 6499.610023398516, 6526.804195874131, 6529.706163222666, 6532.052852336385, 6545.476361891133, 6272.874542509216, 6293.181886354744, 6304.432601065926, 6330.49356051524, 6334.271558770616, 6462.838429039311, 6505.284418714033, 6511.576747511396, 6523.11937888379, 6549.148610680581, 6347.492200624031, 6348.492120630246, 6353.269374021862, 6363.459105976044, 6363.554551181558, 6243.625382477066, 6326.746930122744, 6329.145565348803, 6353.52348573868, 6359.268684101387, 6266.373362663697, 6267.498600111889, 6275.215598050244, 6277.309495955533, 6287.71705273273, 5406.297181366397, 6185.628862268279, 6238.251409830849, 6256.530760192913, 6256.562040657111, 4564.726116433025, 5909.8227053188475, 5954.434328738763, 5961.4038596140035, 6034.064719968412, 6154.969225153845, 6229.3770622939155, 6257.374262573706, 6279.215098112736, 6287.402696743802, 6016.458518733335, 6146.907796382969, 6173.444390004921, 6175.938240617536, 6179.783707570214], "value": 5755.128396286748, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [530.6524226631557, 533.0139242401558, 548.7942021741846, 556.5519756595938, 556.604810584485, 26132.738672613268, 43086.35370469442, 44532.66402007945, 44557.440489582914, 44574.77712611432, 46033.0, 46457.76771116148, 46551.301730473984, 46602.06795864081, 46757.0, 8721.869171962393, 9886.0, 10074.042965918226, 10079.647212347569, 10093.949530252376, 8761.7809554761, 11865.347405892673, 12096.879031209708, 12097.03223742103, 12222.511099556052, 12148.27110373376, 12215.694607634836, 12218.572349967753, 12219.083568732336, 12222.633321000385, 8814.25078868297, 11893.0, 12048.819267710991, 12112.454939527743, 12174.147809653283, 12051.819222711667, 12127.272363658209, 12143.514259429612, 12166.939165304206, 12175.08686848481, 41820.79089604533, 42284.154316913795, 42338.78830605858, 42343.0, 42353.0, 3296.324253528045, 3323.8171900545462, 3346.7489938254485, 3351.078453425303, 3385.5598772159706, 29982.0, 30213.0, 30222.54666180009, 30304.24239394011, 30486.390272194654, 2539.136693524211, 2739.1645548107817, 2753.559430491132, 2782.0123856031223, 2795.7483826455514, 535140.0, 537013.0, 537796.0, 538748.3062584662, 539114.3044284793, 36297.81851090727, 67958.32041679618, 68637.0, 68773.0, 68778.96831547531, 37257.44113838294, 38593.807030965225, 38657.0, 38658.0, 38710.612893870704, 15543.689126217638, 15608.687826243411, 15714.371425142866, 15727.842721572866, 15735.055896646238, 11952.940235298767, 12124.515019399301, 12881.0, 13036.0, 13110.213387196798, 7368.005319281829, 8238.546879921661, 8405.873911891327, 8425.452345597516, 8479.618417171243, 3481.2689335239884, 3481.70405515534, 3488.7846217651972, 3490.3717330880177, 3516.8769093082133, 884.1124199833401, 923.5982347678781, 928.6888892221046, 1018.4042335233823, 1112.6383925224172, 30507.847460762998, 30705.77176912943, 30746.846265768527, 30870.0, 31103.84448077745, 996.9800603988025, 1094.7865166292497, 1107.3224065540712, 1179.9823002654966, 1205.367182229319, 11927.0, 13805.86194138066, 15734.9213253933, 15758.842411575966, 16164.676706465805, 694540.0, 696457.0, 703485.0, 703556.0, 707015.0, 35287.4706879397, 45243.32135017977, 45301.54698453039, 45367.0, 45421.77289113533, 1334.9599512014654, 1375.6217040313993, 1463.8755705764704, 1494.260341131147, 1496.5360738171203, 30105.397892042474, 31381.0, 31467.370652587277, 31872.36255274928, 32254.677453226104, 54351.72824135854, 54608.18087728767, 55254.44745552493, 55298.0, 55298.72350638221, 488342.0, 565423.0, 568618.0, 569117.0, 570463.0, 22647.320580382613, 23822.761772382055, 24853.378665533506, 28046.2988425291, 28418.00536981196, 44193.07006799072, 47784.283235750794, 48071.5192848067, 48429.51570484391, 49698.0, 27691.861540692975, 38653.0, 38768.61231387763, 39362.409563855996, 39855.0, 33432.8298509551, 35283.0, 38468.0, 38968.80515597517, 39586.40620390639, 37922.24155516819, 38411.80794096012, 38669.64656237018, 39332.606673932896, 39758.0, 33233.04375547909, 33997.66002339945, 34562.0, 34834.9549513515, 34939.65060349466, 22021.559568808854, 24800.131995380074, 25118.874405627856, 25425.575852271253, 25796.484070318864], "value": 19276.560872713973, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.093800974724, 391.5731852281013, 394.2174783055636, 395.69926855589745, 414.9336106223005, 20404.89797551012, 20995.790042099576, 21417.357479275608, 21487.24794632188, 21510.032048557812, 18469.337759601633, 20162.798372016274, 20241.0, 21898.01458934348, 21909.561808763814, 5092.847214583559, 5407.053765591026, 5411.053065713484, 5430.945690543083, 5433.864153396157, 5139.511746384088, 5454.7818087276655, 5455.863603409926, 5456.645318054334, 5465.508104270615, 5937.762489500415, 5949.405059494059, 5985.5810093293485, 5987.970060149715, 6001.159837622737, 5868.559858010623, 5958.851028724295, 5965.3736357682255, 5966.075258334965, 6211.968940155315, 6020.036794112964, 6023.247094113236, 6028.879422411571, 6032.336442991267, 6059.1820104286235, 14617.342219600037, 25650.743492565023, 25985.480290394087, 26240.0, 26782.46435071307, 2105.0236666782057, 2174.4455163933253, 2237.966430503544, 2257.9661305080435, 2367.135995361698, 9838.409695418226, 10094.13258547171, 10420.114290285335, 10453.84319235212, 10457.52941117644, 1582.4936020473453, 1636.0, 1688.1643586424675, 1735.6181640039172, 1778.3242367900245, 105562.0, 107814.92185078128, 107976.4601176997, 108178.45910770395, 108815.45592272068, 9540.90459095414, 11729.648110556696, 11846.637636671721, 12104.213226140273, 12300.507979680891, 15223.010504317406, 15342.616434589225, 15364.769528457082, 15368.769468457982, 15369.0, 8607.65569377218, 8811.427257228259, 8813.338999575057, 8876.644934202688, 8935.28517718581, 8686.348523860735, 8702.129787021373, 8704.347173961978, 8776.473411595325, 8833.425827321204, 4401.383806267125, 4570.7257564546235, 4626.6298696104905, 4714.481407045222, 4730.337752714623, 1775.1922875091802, 1957.8727382720078, 1969.5666953270259, 2003.3589251439691, 2006.5284658105625, 903.4850135422746, 927.4759760735163, 927.6753136402206, 955.7992821507561, 959.3811991265594, 20070.59858802816, 21028.474288142614, 21114.15543378248, 21208.0, 21376.57246855054, 935.2938531408879, 959.822432849911, 960.2126256469768, 993.6174572789528, 1026.6150193677372, 10788.298760580537, 11192.160587955774, 11276.041536469327, 11330.886691132982, 11333.943330283295, 586666.0, 591741.0, 593506.0324698349, 619469.0, 620186.0, 93453.53273233591, 123116.0, 124994.37502812428, 125110.0, 131820.0, 1003.6136087606178, 1006.7281833904805, 1022.4172221833633, 1086.766345235778, 1088.3524303039837, 19187.424377268704, 19635.509112272306, 19652.0, 19744.12907376716, 19795.604087917873, 44947.876303092686, 50756.73108172325, 51529.96940061253, 55452.0, 55747.72126139343, 54845.72577137089, 79710.0, 82215.0, 82561.58719206366, 85148.0, 7989.600519974094, 8159.26566609008, 8206.425550211667, 8388.874166887623, 8413.70552030676, 11602.303861768321, 12600.180988235736, 12768.553100641433, 12914.158885045275, 13031.02267329935, 10992.670219893416, 11206.551737930391, 11332.886671133183, 11436.313821170756, 11445.198836081394, 8556.529390883561, 10348.430836304076, 10740.0, 11095.11239100886, 11168.274062185932, 9388.812223755624, 11210.943945280222, 11303.378314192798, 11403.087752979576, 11590.304581725124, 7492.288232617892, 7891.368690504861, 8192.918070819216, 8313.210245026712, 8367.790805229917, 10892.891071089505, 11002.119830413387, 11021.118310535297, 11080.944595276973, 11259.211855170059], "value": 9553.032673403022, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.3550370085309, 1454.7599646058402, 1590.9045457272564, 1838.1911958738153, 2017.8420771492622, 8234.670613175473, 9065.456072635647, 9400.153986141246, 9400.24798016158, 9453.385529940566, 8944.329175311845, 9177.357584969055, 9179.31155163362, 9192.31057670674, 9196.0, 2226.866388016716, 2466.5683505386573, 2507.623856421533, 2548.4011257354505, 2550.2731721459386, 2519.143491212987, 2541.0598078710905, 2543.55487789637, 2544.7709706126443, 2548.388386787167, 2496.4133428644254, 2550.2094350751236, 2551.8468891866455, 2552.923412297634, 2553.5658937980493, 2180.5311857950537, 2409.2772168349425, 2473.13440295895, 2473.567125753004, 2494.7006359236916, 2507.260358194319, 2522.2937577478338, 2542.7965762739123, 2545.6563363945816, 2546.59254519276, 9087.636494540211, 9263.768405789842, 9265.490398028172, 9270.397424167408, 9278.675246366345, 4682.531746825324, 5639.802606908779, 5656.1798539211995, 5662.348829884575, 5666.575006874501, 3611.9886431799137, 3622.9275414491563, 3625.6736893679695, 3715.089802998256, 3724.1806802503406, 1030.6598822388669, 1056.9418681972488, 1057.9682609521726, 1059.56557811297, 1061.3207547169818, 12453.439595218146, 12477.188982716094, 12487.31319777412, 12501.312427816467, 12503.0, 4969.204927211701, 4981.875453113628, 5095.414027386824, 5098.643094983389, 5111.182210846247, 10372.533236004398, 10418.843717344245, 10419.010194031554, 10422.0, 10432.478376081317, 5606.103023516218, 5619.578531610146, 5620.718964051781, 5621.943780562224, 5629.774809007594, 4636.049609829976, 4801.303810947393, 4983.5763960063805, 5099.923501147486, 5120.231965205175, 3469.5836499619704, 3956.6564068705457, 4155.709100362946, 4211.810468528924, 4236.724612900151, 1381.0815807487909, 1403.684171061503, 1407.859214078584, 1408.7886816977536, 1420.31824724133, 917.5824999625155, 926.9258459323238, 939.8778158839307, 955.8279509688323, 973.776031512756, 6014.218151640259, 6050.48570871472, 6066.817995460143, 6145.84635384119, 6152.541527410639, 465.42752414530315, 476.57346674725926, 481.059528621544, 482.59944246275506, 488.94866039066096, 3995.20095980805, 4038.1116154446563, 4101.302778527657, 4107.774072425986, 4121.5878412158545, 10895.455227238606, 10908.236423450282, 11351.829722554168, 11360.659180224606, 11367.0, 11221.270617409842, 11352.772944541062, 11352.94323528377, 11365.977062064454, 11374.31754094757, 8515.19105684959, 8518.190771876662, 8521.914780852112, 8578.18507241811, 8619.655213791504, 5655.971720141538, 5945.464908158114, 5998.130271110752, 6020.4280593343565, 6035.78874739382, 10335.276530643083, 10475.266731328733, 10493.370397776158, 10507.474626268504, 10551.103156231806, 10264.692059238234, 10618.52216650268, 10647.414392208502, 10774.676759697222, 10781.407022613832, 6864.313568643096, 7101.609411482418, 7307.744228951961, 7469.215732348025, 7546.2831031051965, 8623.827523449621, 8755.824883502422, 8836.293096552132, 8852.070532593985, 8853.335999800169, 7610.315071643578, 7815.570143641927, 7824.765257042298, 7880.290773830383, 7955.1249362568915, 7560.243975602396, 7639.465237433326, 7650.273224043707, 7798.33714134305, 7806.0632724073475, 7215.747448839265, 7271.854562908607, 7304.415646748141, 7305.2329505403295, 7311.086114235721, 4818.975905120452, 5011.298418221525, 5098.2352647103235, 5129.204973229155, 5142.254373115952, 5059.569936555434, 5094.719790411563, 5149.279100925798, 5259.289995850511, 5376.381716102698], "value": 4699.833228024608, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.0404248849809, 313.2278932431556, 326.7908538535337, 329.81365528476397, 330.12846086332087, 4719.952800471994, 5228.369553368807, 5349.22436246744, 5406.810761623344, 5438.401775804658, 5343.812966546172, 5355.919661205085, 5377.784888604451, 5379.488948549892, 5385.569154467643, 3620.167361506855, 3948.545917219528, 4115.464989551354, 4119.732217405859, 4134.400511925769, 3130.2174456385906, 3439.5725773803842, 4020.2562525932794, 4135.31767258402, 4154.916901661965, 3843.8852732707483, 3969.027588240885, 4003.7188099808072, 4018.5378681451716, 4043.4541336919433, 3452.58568971725, 4003.4386589230226, 4020.658244049245, 4026.1947610478023, 4039.9596004040172, 3096.5200393938976, 3523.5243242162237, 3542.8919792723286, 3547.733919956014, 3570.268419816387, 4368.929612244989, 4709.317149013408, 4888.315635810989, 5123.077845987721, 5134.563562097226, 3997.860609726243, 4052.7365721228025, 4617.861464156081, 4629.8148074077335, 4639.8144074237325, 5462.289902312714, 5703.372629010805, 5728.68492232927, 5735.6197178171005, 5750.281214848144, 3477.565304336958, 3842.654161125512, 3844.7500912440883, 3923.9215215695526, 3941.2905676978135, 4977.352944117278, 5611.66330020196, 5633.380328163898, 5722.341930677942, 5964.791232306892, 3247.902562923116, 3373.241020770307, 3430.9313813723584, 3981.6416522513114, 3990.184466067932, 3506.2111025019676, 3804.619538046173, 3870.9806450967567, 3888.6111388861455, 4046.1503084352025, 3978.0, 4667.766611669403, 4684.81260749573, 4705.882352941204, 4711.411073615798, 4888.682235654671, 5038.874028149252, 5112.897742045213, 5311.415744268127, 5767.653940763567, 1091.3888222595372, 1321.3525372567374, 1369.205860600849, 1374.3540535948089, 1378.8896888248917, 763.2215140556601, 816.7958010497375, 825.3974598543098, 843.215809297347, 847.894013248344, 4063.8824323310737, 4578.649298456963, 4581.450225972904, 4615.26155815068, 4644.674872758875, 4910.754462276872, 4941.160002799533, 5024.773885175176, 5042.899142017139, 5082.567981721595, 3816.866409675648, 4043.838246470167, 4089.7137200395687, 4433.958019865329, 4534.88895220673, 2303.504746479515, 2623.5500317082974, 2683.4633073385403, 2928.2137895883493, 3133.918798014681, 5493.395726470085, 5698.1452782083015, 5730.312362516442, 6140.508759299245, 6191.0, 5799.3040835100055, 6093.512518998469, 6140.078988151724, 6151.2618485781095, 6173.506119510428, 5195.948040519546, 5657.67044744493, 6144.109104179958, 6175.62946223228, 6176.289726681399, 4879.707217566815, 5397.757100930546, 5916.171735957056, 5964.194833697396, 6010.879782404415, 5235.3717553893775, 5535.225068490495, 5933.465988061095, 5945.702714864153, 5970.194023806731, 6136.8772624548155, 6250.218722659668, 6254.6247225166635, 6257.4681152102585, 6304.148939893239, 3418.4701371287492, 3884.368565202565, 3927.429028388553, 3999.92000160001, 4037.6567991719858, 5633.521150702236, 5915.526757859275, 5932.851678708067, 5985.940140598538, 5998.25021872266, 5323.71080435401, 5823.213866128188, 5827.125931110368, 5828.610269789366, 5831.0, 5500.882160368207, 5799.0, 5799.82600521985, 5817.156512305776, 5862.794802181903, 4625.444946606429, 4759.619230461485, 5189.870253243699, 5212.348456442945, 5505.559555235492, 5784.913226301524, 5827.271591051119, 5831.912521312099, 5842.737076831468, 5845.678487683218, 5204.8178313758835, 5382.255049924488, 5600.851825375869, 5690.886182276414, 5707.8858422832145], "value": 4068.0168118403362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [626.6522080245463, 693.4591019005178, 707.3633729643324, 744.9255074492552, 759.828865592301, 15049.322780474877, 15454.845451545481, 15555.066695998248, 15575.06549607023, 15811.60470988223, 14713.0, 14809.629759255997, 14812.963092583523, 14829.92585037076, 14845.92577037116, 725.405167762435, 748.9400847932179, 888.449161519857, 916.1754421021068, 917.3853518142861, 894.3471265975842, 920.9907900921005, 924.117467818232, 924.1313165624325, 931.1433481197281, 957.4686049242667, 971.8590804333368, 972.377678285894, 975.0444564326964, 978.1148061004767, 759.2066290726204, 782.6830133795822, 783.6316931042458, 966.4925913895191, 968.9660861869871, 687.6458623808743, 708.9858202835915, 710.499098135813, 710.5523520182252, 711.3029231353252, 15343.46297879569, 15408.614784630361, 15409.306581203873, 15419.22903854814, 15480.225988700633, 12311.19977201479, 12366.876331236663, 12448.937755311166, 12641.62075137738, 12697.0, 10783.83824242637, 10933.289336193122, 11014.889851101547, 11230.438478076063, 11399.48702308398, 4008.2985477541315, 4039.858604948842, 4057.594240575919, 4090.9795451022555, 4250.574942505756, 73489.0, 75685.62157189178, 88092.55953720254, 88226.67659985105, 88484.0, 9643.614255429844, 9686.515674216262, 9698.418094914328, 9777.168940639984, 10289.742756431, 22688.071513921503, 22934.0, 22954.311370658907, 22991.885040575024, 22996.96513656856, 6561.507886908501, 7649.961750191324, 7763.9611801941755, 7829.843403131906, 7850.332721718603, 4025.476688030596, 4070.7964601769795, 4109.321961876257, 4125.64931980779, 4353.717008394444, 3607.4408466687705, 3658.9451158232646, 3695.4826324314618, 3698.1863989922176, 3722.274156539449, 670.2359310386164, 673.8820706376463, 673.9427148692318, 675.2201207605224, 679.9524033317621, 550.3313474128953, 565.6379916853217, 568.5309619563876, 598.2567353407255, 609.5702529716539, 7948.920510794818, 8028.0, 8162.551059691774, 8212.794680133044, 8345.332373410112, 505.45663411832425, 580.494969376645, 581.7847396463336, 600.2376981233896, 602.3494625804121, 3786.263168051319, 3840.9039774005314, 3846.8845934622004, 3926.5680775114715, 4088.9182216355507, 88488.55755721309, 90783.0, 91383.0, 91564.0, 95824.0, 56758.92879427026, 77949.0, 78144.21855781483, 81917.0, 87282.1271787274, 53309.466905332, 74124.88812667814, 74976.62511687407, 75336.0, 75729.62135189399, 7404.333609975128, 7409.481336306622, 7583.317501424898, 7654.387648988179, 7828.099768526692, 45670.771646141555, 46607.834804130165, 46801.297980531, 47049.29426058543, 48864.75567622139, 63792.3620763805, 67107.0, 67229.32770672231, 67239.0, 67482.66258668675, 10390.0, 10425.635102771366, 10601.522931468257, 10673.0, 10878.564857405932, 10983.725406864573, 11081.445927703422, 11157.72105697364, 11239.426480292928, 11305.604303849326, 10517.00088491592, 10545.57817687284, 10783.0, 10823.350598964087, 10831.620893268699, 10418.322809017389, 10445.112165465716, 10627.787444251226, 10739.516721747385, 10826.67519974402, 10030.498475076363, 10069.647562335284, 10200.4389758564, 10291.691249262534, 10570.630027948984, 8734.82530349402, 8752.427376847718, 9056.36605437613, 9304.767380815534, 9548.0, 8365.079841217339, 8533.061363249913, 8647.956760216159, 8681.435706679045, 8979.0], "value": 6852.303011699885, "unit": "score"}], "name": "dromaeo_css", "value": 7196.067735122422, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 111.0, 111.0, 111.0, 110.0, 111.0, 111.0, 111.0, 112.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 116.0, 140.0, 197.0, 116.0, 137.0, 135.0, 118.0, 168.0, 115.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 186.0, 185.0, 183.0, 183.0, 184.0, 184.0, 181.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 71.0, 57.0, 124.0, 56.0, 62.0, 57.0, 58.0, 111.0, 55.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 100.0, 133.0, 80.0, 79.0, 76.0, 101.0, 81.0, 82.0, 101.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 86.0, 84.0, 89.0, 126.0, 86.0, 83.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 98.0, 93.0, 92.0, 92.0, 94.0, 93.0, 92.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 86.0, 86.0, 85.0, 85.0, 84.0, 86.0, 100.0], "value": 86.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 59.0, 58.0, 55.0, 58.0, 56.0, 115.0, 55.0, 56.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 78.0, 75.0, 71.0, 69.0, 69.0, 92.0, 99.0, 99.0], "value": 77.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 84.0, 82.0, 85.0, 84.0, 85.0, 85.0, 87.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 132.0, 135.0, 135.0, 134.0, 135.0, 134.0, 134.0, 215.0, 139.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 218.0, 158.0, 195.0, 228.0, 144.0, 197.0, 139.0, 184.0, 219.0], "value": 184.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 66.0, 66.0, 64.0, 67.0, 64.0, 64.0, 65.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1486.1999999999998, "alertThreshold": 2.0}]} 10:15:19 INFO - Return code: 0 10:15:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:15:19 ERROR - # TBPL SUCCESS # 10:15:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:15:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:15:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:15:19 INFO - 2016-05-18 10:15:19.493 screenresolution[1684:12468] starting screenresolution argv=screenresolution get 10:15:19 INFO - 2016-05-18 10:15:19.497 screenresolution[1684:12468] Display 0: 1600x1200x32@60 10:15:19 INFO - 2016-05-18 10:15:19.503 screenresolution[1685:12471] starting screenresolution argv=screenresolution list 10:15:19 INFO - Available Modes on Display 0 10:15:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:15:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:15:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:15:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:15:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:15:19 INFO - Intel Iris: 10:15:19 INFO - Chipset Model: Intel Iris 10:15:19 INFO - Type: GPU 10:15:19 INFO - Bus: Built-In 10:15:19 INFO - VRAM (Dynamic, Max): 1536 MB 10:15:19 INFO - Vendor: Intel (0x8086) 10:15:19 INFO - Device ID: 0x0a2e 10:15:19 INFO - Revision ID: 0x0009 10:15:19 INFO - Displays: 10:15:19 INFO - Display: 10:15:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:15:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:15:19 INFO - Display Serial Number: 1600x1200 60 10:15:19 INFO - Main Display: Yes 10:15:19 INFO - Mirror: Off 10:15:19 INFO - Online: Yes 10:15:19 INFO - Rotation: Supported 10:15:19 INFO - Return code: 0 10:15:19 INFO - Running post-action listener: _resource_record_post_action 10:15:19 INFO - ##### 10:15:19 INFO - ##### Finished run-tests step (success) 10:15:19 INFO - ##### 10:15:19 INFO - Running post-run listener: _resource_record_post_run 10:15:19 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32513536; Write bytes: 696585728; Read time: 322; Write time: 1880 10:15:19 INFO - TinderboxPrint: CPU usage
53.5% 10:15:19 INFO - TinderboxPrint: I/O read bytes / time
32,513,536 / 322 10:15:19 INFO - TinderboxPrint: I/O write bytes / time
696,585,728 / 1,880 10:15:19 INFO - TinderboxPrint: CPU idle
2,253.3 (46.5%) 10:15:19 INFO - TinderboxPrint: CPU system
961.2 (19.8%) 10:15:19 INFO - TinderboxPrint: CPU user
1,631.2 (33.7%) 10:15:19 INFO - TinderboxPrint: Swap in / out
423,747,584 / 0 10:15:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 374618624; Write bytes: 368576512; Read time: 28056; Write time: 698 10:15:19 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 31583744; Write bytes: 298927616; Read time: 308; Write time: 1129 10:15:20 INFO - Running post-run listener: _upload_blobber_files 10:15:20 INFO - Blob upload gear active. 10:15:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:15:20 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 10:15:20 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:15:20 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:15:20 INFO - (blobuploader) - INFO - Open directory for files ... 10:15:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:15:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:15:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:21 INFO - (blobuploader) - INFO - Done attempting. 10:15:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:15:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:15:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:22 INFO - (blobuploader) - INFO - Done attempting. 10:15:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:15:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:15:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:25 INFO - (blobuploader) - INFO - Done attempting. 10:15:25 INFO - (blobuploader) - INFO - Iteration through files over. 10:15:25 INFO - Return code: 0 10:15:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:15:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:15:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7207a5df381853e457817a89eb6763b122196bc4ab2880f18e02a19c0ed5999bffc1f1e2b54f3ef3ed67e8ea2f34c2dd4d01e7ea63cb15d6ed359d2ec1e1ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0503299526823e03d2e5ce4fca13f4eb9c1c2fd64729e902c0f3b7816d7e440e61be853ec290010691f98937dc6f2ef3d53e4dce4e382892f349467e382ebfb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a59a885b45f5428fe49a496aec56571ca34e062e00baf736aeba5204dd8b9f04a5ed678e8011e423e18be8f80df3639cac3d048494db621be5c84deb774e4b2"} 10:15:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:15:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:15:25 INFO - Contents: 10:15:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7207a5df381853e457817a89eb6763b122196bc4ab2880f18e02a19c0ed5999bffc1f1e2b54f3ef3ed67e8ea2f34c2dd4d01e7ea63cb15d6ed359d2ec1e1ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0503299526823e03d2e5ce4fca13f4eb9c1c2fd64729e902c0f3b7816d7e440e61be853ec290010691f98937dc6f2ef3d53e4dce4e382892f349467e382ebfb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a59a885b45f5428fe49a496aec56571ca34e062e00baf736aeba5204dd8b9f04a5ed678e8011e423e18be8f80df3639cac3d048494db621be5c84deb774e4b2"} 10:15:25 INFO - Running post-run listener: copy_logs_to_upload_dir 10:15:25 INFO - Copying logs to upload dir... 10:15:25 INFO - mkdir: /builds/slave/test/build/upload/logs 10:15:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.150477 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-18 10:15:25.465414) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 10:15:25.468855) ========= 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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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/Firefox/sha512/ff7207a5df381853e457817a89eb6763b122196bc4ab2880f18e02a19c0ed5999bffc1f1e2b54f3ef3ed67e8ea2f34c2dd4d01e7ea63cb15d6ed359d2ec1e1ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0503299526823e03d2e5ce4fca13f4eb9c1c2fd64729e902c0f3b7816d7e440e61be853ec290010691f98937dc6f2ef3d53e4dce4e382892f349467e382ebfb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a59a885b45f5428fe49a496aec56571ca34e062e00baf736aeba5204dd8b9f04a5ed678e8011e423e18be8f80df3639cac3d048494db621be5c84deb774e4b2"} build_url:https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/HGjGpvzDRlekmMZPn8Hz9w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7207a5df381853e457817a89eb6763b122196bc4ab2880f18e02a19c0ed5999bffc1f1e2b54f3ef3ed67e8ea2f34c2dd4d01e7ea63cb15d6ed359d2ec1e1ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0503299526823e03d2e5ce4fca13f4eb9c1c2fd64729e902c0f3b7816d7e440e61be853ec290010691f98937dc6f2ef3d53e4dce4e382892f349467e382ebfb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a59a885b45f5428fe49a496aec56571ca34e062e00baf736aeba5204dd8b9f04a5ed678e8011e423e18be8f80df3639cac3d048494db621be5c84deb774e4b2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 10:15:25.498149) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 10:15:25.498492) ========= 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.3JpeZtBT6R/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.V3XgOlAjT4/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.005050 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 10:15:25.547601) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 10:15:25.547917) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 10:15:25.548303) ========= ========= Total master_lag: 0.17 =========