builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0358 starttime: 1463053470.64 results: success (0) buildid: 20160512025507 builduid: 88eae7355762482f817f8aea258c440a revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:30.645047) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:30.645512) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:30.645793) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:30.665535) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:30.665924) ========= 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.jek88u00jG/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.lA3pPYZsOj/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-12 04:44:30-- 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% 135M=0s 2016-05-12 04:44:30 (135 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139999 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:30.823574) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:30.827014) ========= 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.jek88u00jG/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.lA3pPYZsOj/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.025067 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:30.878941) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:30.879374) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c3f5e6079284a7b7053c41f05d0fe06ff031db03 --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 c3f5e6079284a7b7053c41f05d0fe06ff031db03 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jek88u00jG/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.lA3pPYZsOj/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-12 04:44:30,955 truncating revision to first 12 chars 2016-05-12 04:44:30,955 Setting DEBUG logging. 2016-05-12 04:44:30,955 attempt 1/10 2016-05-12 04:44:30,955 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c3f5e6079284?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 04:44:31,547 unpacking tar archive at: mozilla-central-c3f5e6079284/testing/mozharness/ program finished with exit code 0 elapsedTime=0.849037 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:31.748050) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:31.748374) ========= script_repo_revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:31.748768) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:31.749067) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:31.765963) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-05-12 04:44:31.766279) ========= /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.jek88u00jG/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.lA3pPYZsOj/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:44:31 INFO - MultiFileLogger online at 20160512 04:44:31 in /builds/slave/test 04:44:31 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 04:44:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:44:31 INFO - {'append_to_log': False, 04:44:31 INFO - 'base_work_dir': '/builds/slave/test', 04:44:31 INFO - 'blob_upload_branch': 'Firefox', 04:44:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:44:31 INFO - 'branch': 'Firefox', 04:44:31 INFO - 'buildbot_json_path': 'buildprops.json', 04:44:31 INFO - 'config_files': ('talos/mac_config.py',), 04:44:31 INFO - 'default_actions': ('clobber', 04:44:31 INFO - 'read-buildbot-config', 04:44:31 INFO - 'download-and-extract', 04:44:31 INFO - 'populate-webroot', 04:44:31 INFO - 'create-virtualenv', 04:44:31 INFO - 'install', 04:44:31 INFO - 'run-tests'), 04:44:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:44:31 INFO - 'download_minidump_stackwalk': True, 04:44:31 INFO - 'download_symbols': 'ondemand', 04:44:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:44:31 INFO - 'tooltool.py': '/tools/tooltool.py', 04:44:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:44:31 INFO - '/tools/misc-python/virtualenv.py')}, 04:44:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:44:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:44:31 INFO - 'installer_path': 'installer.exe', 04:44:31 INFO - 'log_level': 'info', 04:44:31 INFO - 'log_name': 'talos', 04:44:31 INFO - 'log_to_console': True, 04:44:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:44:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:44:31 INFO - 'opt_config_files': (), 04:44:31 INFO - 'pip_index': False, 04:44:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:31 INFO - 'cmd': ('bash', 04:44:31 INFO - '-c', 04:44:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:31 INFO - 'enabled': True, 04:44:31 INFO - 'halt_on_failure': False, 04:44:31 INFO - 'name': 'check_screen_resolution'},), 04:44:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:31 INFO - 'cmd': ('bash', 04:44:31 INFO - '-c', 04:44:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:31 INFO - 'enabled': True, 04:44:31 INFO - 'halt_on_failure': False, 04:44:31 INFO - 'name': 'check_screen_resolution'},), 04:44:31 INFO - 'run_cmd_checks_enabled': True, 04:44:31 INFO - 'sps_profile': False, 04:44:31 INFO - 'sps_profile_interval': 0, 04:44:31 INFO - 'suite': 'dromaeojs', 04:44:31 INFO - 'system_bits': '32', 04:44:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:44:31 INFO - 'title': 't-yosemite-r7-0358', 04:44:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:44:31 INFO - 'use_talos_json': True, 04:44:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:44:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:44:31 INFO - 'work_dir': 'build'} 04:44:31 INFO - ##### 04:44:31 INFO - ##### Running clobber step. 04:44:31 INFO - ##### 04:44:31 INFO - Running pre-action listener: _resource_record_pre_action 04:44:31 INFO - Running main action method: clobber 04:44:31 INFO - rmtree: /builds/slave/test/build 04:44:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:33 INFO - Running post-action listener: _resource_record_post_action 04:44:33 INFO - ##### 04:44:33 INFO - ##### Finished clobber step (success) 04:44:33 INFO - ##### 04:44:33 INFO - ##### 04:44:33 INFO - ##### Running read-buildbot-config step. 04:44:33 INFO - ##### 04:44:33 INFO - Running pre-action listener: _resource_record_pre_action 04:44:33 INFO - Running main action method: read_buildbot_config 04:44:33 INFO - Using buildbot properties: 04:44:33 INFO - { 04:44:33 INFO - "project": "", 04:44:33 INFO - "product": "firefox", 04:44:33 INFO - "script_repo_revision": "production", 04:44:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:44:33 INFO - "repository": "", 04:44:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:44:33 INFO - "buildid": "20160512025507", 04:44:33 INFO - "pgo_build": "False", 04:44:33 INFO - "basedir": "/builds/slave/test", 04:44:33 INFO - "buildnumber": 248, 04:44:33 INFO - "slavename": "t-yosemite-r7-0358", 04:44:33 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:44:33 INFO - "platform": "yosemite_r7", 04:44:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:44:33 INFO - "branch": "mozilla-central", 04:44:33 INFO - "repo_path": "mozilla-central", 04:44:33 INFO - "revision": "c3f5e6079284a7b7053c41f05d0fe06ff031db03", 04:44:33 INFO - "stage_platform": "macosx64", 04:44:33 INFO - "builduid": "88eae7355762482f817f8aea258c440a", 04:44:33 INFO - "slavebuilddir": "test" 04:44:33 INFO - } 04:44:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:44:33 INFO - Running post-action listener: _resource_record_post_action 04:44:33 INFO - ##### 04:44:33 INFO - ##### Finished read-buildbot-config step (success) 04:44:33 INFO - ##### 04:44:33 INFO - ##### 04:44:33 INFO - ##### Running download-and-extract step. 04:44:33 INFO - ##### 04:44:33 INFO - Running pre-action listener: _resource_record_pre_action 04:44:33 INFO - Running main action method: download_and_extract 04:44:33 INFO - mkdir: /builds/slave/test/build/tests 04:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:33 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:44:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:44:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:44:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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 04:44:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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 04:44:36 INFO - Downloaded 1199 bytes. 04:44:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:44:36 INFO - Using the following test package requirements: 04:44:36 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:44:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:36 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:44:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:36 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:44:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:36 INFO - u'jsshell-mac.zip'], 04:44:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:36 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:44:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:44:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:36 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:44:36 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:36 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:44:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:36 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:44:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:36 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:44:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:36 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:44:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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 04:44:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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 04:44:37 INFO - Downloaded 18151194 bytes. 04:44:37 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'] 04:44:37 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 04:44:40 INFO - Return code: 0 04:44:40 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 04:44:40 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')]} 04:44:40 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:44:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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 04:44:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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 04:44:45 INFO - Downloaded 18151194 bytes. 04:44:45 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'] 04:44:45 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 04:44:49 INFO - Return code: 0 04:44: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')]} 04:44:49 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:44:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:44:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:44:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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 04:44:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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 04:44:51 INFO - Downloaded 10840239 bytes. 04:44:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:44:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:44:51 INFO - Return code: 0 04:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:51 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:44:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:44:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:44:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:44:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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 04:44:54 INFO - Downloaded 109833510 bytes. 04:44:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:44:54 INFO - mkdir: /builds/slave/test/properties 04:44:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:44:54 INFO - Writing to file /builds/slave/test/properties/build_url 04:44:54 INFO - Contents: 04:44:54 INFO - build_url:https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:44:55 INFO - Running post-action listener: _resource_record_post_action 04:44:55 INFO - Running post-action listener: set_extra_try_arguments 04:44:55 INFO - ##### 04:44:55 INFO - ##### Finished download-and-extract step (success) 04:44:55 INFO - ##### 04:44:55 INFO - ##### 04:44:55 INFO - ##### Running populate-webroot step. 04:44:55 INFO - ##### 04:44:55 INFO - Running pre-action listener: _resource_record_pre_action 04:44:55 INFO - Running main action method: populate_webroot 04:44:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:44:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:44:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:44:55 INFO - '--tppagecycles', 04:44:55 INFO - '7'], 04:44:55 INFO - 'tests': ['tsvgm']}}, 04:44:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:44:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:44:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:44:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:44:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:55 INFO - 'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:55 INFO - 'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['damp', 'tps']}, 04:44:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:55 INFO - 'tests': ['damp', 'tps']}, 04:44:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['dromaeo_dom']}, 04:44:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:44:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['a11yr', 04:44:55 INFO - 'ts_paint', 04:44:55 INFO - 'tpaint', 04:44:55 INFO - 'sessionrestore', 04:44:55 INFO - 'sessionrestore_no_auto_restore', 04:44:55 INFO - 'tabpaint']}, 04:44:55 INFO - 'other-e10s': {'tests': ['a11yr', 04:44:55 INFO - 'ts_paint', 04:44:55 INFO - 'tpaint', 04:44:55 INFO - 'sessionrestore', 04:44:55 INFO - 'sessionrestore_no_auto_restore', 04:44:55 INFO - 'tabpaint']}, 04:44:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:44:55 INFO - 'ts_paint', 04:44:55 INFO - 'tpaint', 04:44:55 INFO - 'sessionrestore', 04:44:55 INFO - 'sessionrestore_no_auto_restore', 04:44:55 INFO - 'tabpaint']}, 04:44:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:44:55 INFO - 'ts_paint', 04:44:55 INFO - 'tpaint', 04:44:55 INFO - 'sessionrestore', 04:44:55 INFO - 'sessionrestore_no_auto_restore', 04:44:55 INFO - 'tabpaint']}, 04:44:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['a11yr', 04:44:55 INFO - 'ts_paint', 04:44:55 INFO - 'tpaint', 04:44:55 INFO - 'sessionrestore', 04:44:55 INFO - 'sessionrestore_no_auto_restore', 04:44:55 INFO - 'tabpaint']}, 04:44:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['a11yr', 04:44:55 INFO - 'ts_paint', 04:44:55 INFO - 'tpaint', 04:44:55 INFO - 'sessionrestore', 04:44:55 INFO - 'sessionrestore_no_auto_restore', 04:44:55 INFO - 'tabpaint']}, 04:44:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['tsvgx', 04:44:55 INFO - 'tsvgr_opacity', 04:44:55 INFO - 'tart', 04:44:55 INFO - 'tscrollx', 04:44:55 INFO - 'cart']}, 04:44:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:44:55 INFO - 'tsvgr_opacity', 04:44:55 INFO - 'tart', 04:44:55 INFO - 'tscrollx', 04:44:55 INFO - 'cart']}, 04:44:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:55 INFO - 'talos_options': ['--disable-e10s'], 04:44:55 INFO - 'tests': ['tp5o']}, 04:44:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:55 INFO - 'tests': ['tp5o']}, 04:44:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:55 INFO - 'talos_options': ['--disable-e10s', 04:44:55 INFO - '--xperf_path', 04:44:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:55 INFO - 'tests': ['tp5n']}, 04:44:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:44:55 INFO - 'talos_options': ['--xperf_path', 04:44:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:55 INFO - 'tests': ['tp5n']}}, 04:44:55 INFO - 'talos.zip': {'path': '', 04:44:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:44:55 INFO - Running post-action listener: _resource_record_post_action 04:44:55 INFO - ##### 04:44:55 INFO - ##### Finished populate-webroot step (success) 04:44:55 INFO - ##### 04:44:55 INFO - ##### 04:44:55 INFO - ##### Running create-virtualenv step. 04:44:55 INFO - ##### 04:44:55 INFO - Running pre-action listener: _resource_record_pre_action 04:44:55 INFO - Running main action method: create_virtualenv 04:44:55 INFO - Creating virtualenv /builds/slave/test/build/venv 04:44:55 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 04:44:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:44:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:44:55 INFO - Using real prefix '/tools/python27' 04:44:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:44:56 INFO - Installing distribute.............................................................................................................................................................................................done. 04:44:59 INFO - Installing pip.................done. 04:44:59 INFO - Return code: 0 04:44:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:44: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')]} 04:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10196ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82467230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, '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.jek88u00jG/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.lA3pPYZsOj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:44:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:59 INFO - 'HOME': '/Users/cltbld', 04:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:59 INFO - 'LOGNAME': 'cltbld', 04:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:59 INFO - 'MOZ_NO_REMOTE': '1', 04:44:59 INFO - 'NO_EM_RESTART': '1', 04:44:59 INFO - 'PAGER': '/bin/cat', 04:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:59 INFO - 'PWD': '/builds/slave/test', 04:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:59 INFO - 'SHELL': '/bin/bash', 04:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:44:59 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:59 INFO - 'USER': 'cltbld', 04:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:59 INFO - 'XPC_FLAGS': '0x0', 04:44:59 INFO - 'XPC_SERVICE_NAME': '0', 04:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:59 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 04:44:59 INFO - Cleaning up... 04:44:59 INFO - Return code: 0 04:44:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:44: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')]} 04:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10196ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82467230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, '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.jek88u00jG/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.lA3pPYZsOj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:44:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:59 INFO - 'HOME': '/Users/cltbld', 04:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:59 INFO - 'LOGNAME': 'cltbld', 04:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:59 INFO - 'MOZ_NO_REMOTE': '1', 04:44:59 INFO - 'NO_EM_RESTART': '1', 04:44:59 INFO - 'PAGER': '/bin/cat', 04:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:59 INFO - 'PWD': '/builds/slave/test', 04:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:59 INFO - 'SHELL': '/bin/bash', 04:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:44:59 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:59 INFO - 'USER': 'cltbld', 04:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:59 INFO - 'XPC_FLAGS': '0x0', 04:44:59 INFO - 'XPC_SERVICE_NAME': '0', 04:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:00 INFO - Downloading/unpacking psutil>=0.7.1 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45: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 04:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:03 INFO - Installing collected packages: psutil 04:45:03 INFO - Running setup.py install for psutil 04:45:03 INFO - building 'psutil._psutil_osx' extension 04:45:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:45:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:45:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:45:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:45:03 INFO - building 'psutil._psutil_posix' extension 04:45:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:45:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:45:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:45:04 INFO - ^ 04:45:04 INFO - 1 warning generated. 04:45:04 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 04:45:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:04 INFO - Successfully installed psutil 04:45:04 INFO - Cleaning up... 04:45:04 INFO - Return code: 0 04:45:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10196ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82467230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, '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.jek88u00jG/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.lA3pPYZsOj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:45:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:04 INFO - 'HOME': '/Users/cltbld', 04:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:04 INFO - 'LOGNAME': 'cltbld', 04:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:04 INFO - 'MOZ_NO_REMOTE': '1', 04:45:04 INFO - 'NO_EM_RESTART': '1', 04:45:04 INFO - 'PAGER': '/bin/cat', 04:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:04 INFO - 'PWD': '/builds/slave/test', 04:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:04 INFO - 'SHELL': '/bin/bash', 04:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:45:04 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:04 INFO - 'USER': 'cltbld', 04:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:04 INFO - 'XPC_FLAGS': '0x0', 04:45:04 INFO - 'XPC_SERVICE_NAME': '0', 04:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:45:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:45:07 INFO - Installing collected packages: mozsystemmonitor 04:45:07 INFO - Running setup.py install for mozsystemmonitor 04:45:07 INFO - Successfully installed mozsystemmonitor 04:45:07 INFO - Cleaning up... 04:45:07 INFO - Return code: 0 04:45:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10196ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82467230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, '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.jek88u00jG/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.lA3pPYZsOj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:45:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:07 INFO - 'HOME': '/Users/cltbld', 04:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:07 INFO - 'LOGNAME': 'cltbld', 04:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:07 INFO - 'MOZ_NO_REMOTE': '1', 04:45:07 INFO - 'NO_EM_RESTART': '1', 04:45:07 INFO - 'PAGER': '/bin/cat', 04:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:07 INFO - 'PWD': '/builds/slave/test', 04:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:07 INFO - 'SHELL': '/bin/bash', 04:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:45:07 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:07 INFO - 'USER': 'cltbld', 04:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:07 INFO - 'XPC_FLAGS': '0x0', 04:45:07 INFO - 'XPC_SERVICE_NAME': '0', 04:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:07 INFO - Downloading/unpacking blobuploader==1.2.4 04:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:10 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:11 INFO - Downloading docopt-0.6.1.tar.gz 04:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:11 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:11 INFO - Running setup.py install for blobuploader 04:45:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:11 INFO - Running setup.py install for requests 04:45:11 INFO - Running setup.py install for docopt 04:45:12 INFO - Successfully installed blobuploader requests docopt 04:45:12 INFO - Cleaning up... 04:45:12 INFO - Return code: 0 04:45:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:12 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 0x10196ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82467230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, '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.jek88u00jG/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.lA3pPYZsOj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:45:12 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 04:45:12 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 04:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:12 INFO - 'HOME': '/Users/cltbld', 04:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:12 INFO - 'LOGNAME': 'cltbld', 04:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:12 INFO - 'MOZ_NO_REMOTE': '1', 04:45:12 INFO - 'NO_EM_RESTART': '1', 04:45:12 INFO - 'PAGER': '/bin/cat', 04:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:12 INFO - 'PWD': '/builds/slave/test', 04:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:12 INFO - 'SHELL': '/bin/bash', 04:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:45:12 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:12 INFO - 'USER': 'cltbld', 04:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:12 INFO - 'XPC_FLAGS': '0x0', 04:45:12 INFO - 'XPC_SERVICE_NAME': '0', 04:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:12 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-z_kfS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:12 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Dyk4Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:12 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-GwFcRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:12 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-TanTaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:12 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-mLdB1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:12 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-rabY1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-_E1z8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-oKaO_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-DkHdFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-U2FCy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-46TNth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-AYyQXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-5Loii3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-reHffi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ygXPnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:13 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-IFKKWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:14 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-B3nfvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:45:14 INFO - Running setup.py install for manifestparser 04:45:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:45:14 INFO - Running setup.py install for mozcrash 04:45:14 INFO - Running setup.py install for mozdebug 04:45:14 INFO - Running setup.py install for mozdevice 04:45:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:45:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:45:14 INFO - Running setup.py install for mozfile 04:45:14 INFO - Running setup.py install for mozhttpd 04:45:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:45:15 INFO - Running setup.py install for mozinfo 04:45:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:45:15 INFO - Running setup.py install for mozInstall 04:45:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:45:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:45:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:45:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:45:15 INFO - Running setup.py install for mozleak 04:45:15 INFO - Running setup.py install for mozlog 04:45:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:45:15 INFO - Running setup.py install for moznetwork 04:45:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:45:15 INFO - Running setup.py install for mozprocess 04:45:15 INFO - Running setup.py install for mozprofile 04:45:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:45:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:45:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:45:16 INFO - Running setup.py install for mozrunner 04:45:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:45:16 INFO - Running setup.py install for mozscreenshot 04:45:16 INFO - Running setup.py install for moztest 04:45:16 INFO - Running setup.py install for mozversion 04:45:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:45:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:45:16 INFO - Cleaning up... 04:45:16 INFO - Return code: 0 04:45:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10196ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82467230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, '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.jek88u00jG/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.lA3pPYZsOj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:16 INFO - 'HOME': '/Users/cltbld', 04:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:16 INFO - 'LOGNAME': 'cltbld', 04:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:16 INFO - 'MOZ_NO_REMOTE': '1', 04:45:16 INFO - 'NO_EM_RESTART': '1', 04:45:16 INFO - 'PAGER': '/bin/cat', 04:45:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:16 INFO - 'PWD': '/builds/slave/test', 04:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:16 INFO - 'SHELL': '/bin/bash', 04:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:45:16 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:16 INFO - 'USER': 'cltbld', 04:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:16 INFO - 'XPC_FLAGS': '0x0', 04:45:16 INFO - 'XPC_SERVICE_NAME': '0', 04:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:16 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-KtOmUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:17 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)) 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-y89Ze3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:17 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)) 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-uzNwRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-eAtzoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-HpPKIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-KmbxHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Fm4CMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-AOekO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Fydmql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:17 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-vyp7O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:18 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)) 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-sYCnjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:18 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)) 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ZrEXPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:18 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)) 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-SSDG8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-6UJ7xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-QJllMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Fd7In0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:18 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-02X_hu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:45:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:21 INFO - Downloading blessings-1.6.tar.gz 04:45:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:45:21 INFO - Installing collected packages: blessings 04:45:21 INFO - Running setup.py install for blessings 04:45:21 INFO - Successfully installed blessings 04:45:21 INFO - Cleaning up... 04:45:21 INFO - Return code: 0 04:45:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:45:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:21 INFO - Reading from file tmpfile_stdout 04:45:21 INFO - Current package versions: 04:45:21 INFO - blessings == 1.6 04:45:21 INFO - blobuploader == 1.2.4 04:45:21 INFO - docopt == 0.6.1 04:45:21 INFO - manifestparser == 1.1 04:45:21 INFO - mozInstall == 1.12 04:45:21 INFO - mozcrash == 0.17 04:45:21 INFO - mozdebug == 0.1 04:45:21 INFO - mozdevice == 0.48 04:45:21 INFO - mozfile == 1.2 04:45:21 INFO - mozhttpd == 0.7 04:45:21 INFO - mozinfo == 0.9 04:45:21 INFO - mozleak == 0.1 04:45:21 INFO - mozlog == 3.2 04:45:21 INFO - moznetwork == 0.27 04:45:21 INFO - mozprocess == 0.22 04:45:21 INFO - mozprofile == 0.28 04:45:21 INFO - mozrunner == 6.11 04:45:21 INFO - mozscreenshot == 0.1 04:45:21 INFO - mozsystemmonitor == 0.0 04:45:21 INFO - moztest == 0.7 04:45:21 INFO - mozversion == 1.4 04:45:21 INFO - psutil == 3.1.1 04:45:21 INFO - requests == 1.2.3 04:45:21 INFO - wsgiref == 0.1.2 04:45:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:22 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')]} 04:45:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:22 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 0x10196ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82467230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, '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.jek88u00jG/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.lA3pPYZsOj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:45:22 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 04:45:22 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 04:45:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:45:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:22 INFO - 'HOME': '/Users/cltbld', 04:45:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:22 INFO - 'LOGNAME': 'cltbld', 04:45:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:22 INFO - 'MOZ_NO_REMOTE': '1', 04:45:22 INFO - 'NO_EM_RESTART': '1', 04:45:22 INFO - 'PAGER': '/bin/cat', 04:45:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:22 INFO - 'PWD': '/builds/slave/test', 04:45:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:22 INFO - 'SHELL': '/bin/bash', 04:45:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:45:22 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:45:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:22 INFO - 'USER': 'cltbld', 04:45:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:22 INFO - 'XPC_FLAGS': '0x0', 04:45:22 INFO - 'XPC_SERVICE_NAME': '0', 04:45:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:22 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)) 04:45:22 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)) 04:45:22 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)) 04:45:22 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)) 04:45:22 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)) 04:45:22 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)) 04:45:22 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)) 04:45:22 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)) 04:45:22 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)) 04:45:22 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)) 04:45:22 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)) 04:45:22 INFO - Cleaning up... 04:45:22 INFO - Return code: 0 04:45:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:45:22 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')]} 04:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:22 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')]} 04:45:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:22 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 0x10196ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82467230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, '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.jek88u00jG/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.lA3pPYZsOj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:45:22 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 04:45:22 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 04:45:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:45:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:22 INFO - 'HOME': '/Users/cltbld', 04:45:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:22 INFO - 'LOGNAME': 'cltbld', 04:45:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:22 INFO - 'MOZ_NO_REMOTE': '1', 04:45:22 INFO - 'NO_EM_RESTART': '1', 04:45:22 INFO - 'PAGER': '/bin/cat', 04:45:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:22 INFO - 'PWD': '/builds/slave/test', 04:45:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:22 INFO - 'SHELL': '/bin/bash', 04:45:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:45:22 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:45:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:22 INFO - 'USER': 'cltbld', 04:45:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:22 INFO - 'XPC_FLAGS': '0x0', 04:45:22 INFO - 'XPC_SERVICE_NAME': '0', 04:45:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:23 INFO - Downloading/unpacking jsonschema 04:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:45:25 INFO - Downloading/unpacking functools32 (from jsonschema) 04:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:26 INFO - Downloading functools32-3.2.3-2.tar.gz 04:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:45:26 INFO - warning: no files found matching '*.txt' 04:45:26 INFO - no previously-included directories found matching 'build' 04:45:26 INFO - no previously-included directories found matching 'dist' 04:45:26 INFO - no previously-included directories found matching '.git*' 04:45:26 INFO - Installing collected packages: jsonschema, functools32 04:45:26 INFO - Running setup.py install for functools32 04:45:26 INFO - warning: no files found matching '*.txt' 04:45:26 INFO - no previously-included directories found matching 'build' 04:45:26 INFO - no previously-included directories found matching 'dist' 04:45:26 INFO - no previously-included directories found matching '.git*' 04:45:26 INFO - Successfully installed jsonschema functools32 04:45:26 INFO - Cleaning up... 04:45:26 INFO - Return code: 0 04:45:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:26 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 0x10196ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101972570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f82467230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, '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.jek88u00jG/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.lA3pPYZsOj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 04:45:26 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 04:45:26 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 04:45:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:45:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:26 INFO - 'HOME': '/Users/cltbld', 04:45:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:26 INFO - 'LOGNAME': 'cltbld', 04:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:26 INFO - 'MOZ_NO_REMOTE': '1', 04:45:26 INFO - 'NO_EM_RESTART': '1', 04:45:26 INFO - 'PAGER': '/bin/cat', 04:45:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:26 INFO - 'PWD': '/builds/slave/test', 04:45:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:26 INFO - 'SHELL': '/bin/bash', 04:45:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:45:26 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:45:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:26 INFO - 'USER': 'cltbld', 04:45:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:26 INFO - 'XPC_FLAGS': '0x0', 04:45:26 INFO - 'XPC_SERVICE_NAME': '0', 04:45:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:26 INFO - Downloading/unpacking flake8 04:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:45:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:45:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:45:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:45:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:45:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:45:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:45:30 INFO - no previously-included directories found matching 'docs/_build' 04:45:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:45:30 INFO - Running setup.py install for pep8 04:45:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:45:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:45:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:45:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:45:30 INFO - no previously-included directories found matching 'docs/_build' 04:45:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:45:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:45:30 INFO - Cleaning up... 04:45:31 INFO - Return code: 0 04:45:31 INFO - Running post-action listener: _resource_record_post_action 04:45:31 INFO - Running post-action listener: _start_resource_monitoring 04:45:31 INFO - Starting resource monitoring. 04:45:31 INFO - ##### 04:45:31 INFO - ##### Finished create-virtualenv step (success) 04:45:31 INFO - ##### 04:45:31 INFO - ##### 04:45:31 INFO - ##### Running install step. 04:45:31 INFO - ##### 04:45:31 INFO - Running pre-action listener: _resource_record_pre_action 04:45:31 INFO - Running main action method: install 04:45:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:31 INFO - Reading from file tmpfile_stdout 04:45:31 INFO - Detecting whether we're running mozinstall >=1.0... 04:45:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:45:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:45:31 INFO - Reading from file tmpfile_stdout 04:45:31 INFO - Output received: 04:45:31 INFO - Usage: mozinstall [options] installer 04:45:31 INFO - Options: 04:45:31 INFO - -h, --help show this help message and exit 04:45:31 INFO - -d DEST, --destination=DEST 04:45:31 INFO - Directory to install application into. [default: 04:45:31 INFO - "/builds/slave/test"] 04:45:31 INFO - --app=APP Application being installed. [default: firefox] 04:45:31 INFO - mkdir: /builds/slave/test/build/application 04:45:31 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'] 04:45:31 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 04:46:06 INFO - Reading from file tmpfile_stdout 04:46:06 INFO - Output received: 04:46:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:46:06 INFO - Running post-action listener: _resource_record_post_action 04:46:06 INFO - ##### 04:46:06 INFO - ##### Finished install step (success) 04:46:06 INFO - ##### 04:46:06 INFO - ##### 04:46:06 INFO - ##### Running run-tests step. 04:46:06 INFO - ##### 04:46:06 INFO - Running pre-action listener: _resource_record_pre_action 04:46:06 INFO - Running main action method: run_tests 04:46:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:06 INFO - 2016-05-12 04:46:06.032 screenresolution[1640:7467] starting screenresolution argv=screenresolution get 04:46:06 INFO - 2016-05-12 04:46:06.035 screenresolution[1640:7467] Display 0: 1024x768x32@60 04:46:06 INFO - 2016-05-12 04:46:06.041 screenresolution[1641:7469] starting screenresolution argv=screenresolution list 04:46:06 INFO - Available Modes on Display 0 04:46:06 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 04:46:06 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 04:46:06 INFO - Intel Iris: 04:46:06 INFO - Chipset Model: Intel Iris 04:46:06 INFO - Type: GPU 04:46:06 INFO - Bus: Built-In 04:46:06 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:06 INFO - Vendor: Intel (0x8086) 04:46:06 INFO - Device ID: 0x0a2e 04:46:06 INFO - Revision ID: 0x0009 04:46:06 INFO - Displays: 04:46:06 INFO - Display: 04:46:06 INFO - Resolution: 1024 x 768 @ 60 Hz 04:46:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:06 INFO - Display Serial Number: 1024x768 60 04:46:06 INFO - Main Display: Yes 04:46:06 INFO - Mirror: Off 04:46:06 INFO - Online: Yes 04:46:06 INFO - Rotation: Supported 04:46:06 INFO - Return code: 0 04:46:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:46:06 INFO - Python 2.7.3 04:46:06 INFO - Return code: 0 04:46:06 INFO - grabbing minidump binary from tooltool 04:46:06 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')]} 04:46:06 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 0x101810d30>, '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 0x101634db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101807b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:46:06 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 04:46:06 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 04:46:06 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 04:46:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:46:06 INFO - Return code: 0 04:46:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:46:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:46:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:46:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:46:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:46:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:46:07 INFO - Return code: 0 04:46:07 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/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--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 04:46:07 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/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0358 --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 04:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jek88u00jG/Render', 04:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:07 INFO - 'HOME': '/Users/cltbld', 04:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:07 INFO - 'LOGNAME': 'cltbld', 04:46:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:46:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:07 INFO - 'MOZ_NO_REMOTE': '1', 04:46:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:46:07 INFO - 'NO_EM_RESTART': '1', 04:46:07 INFO - 'PAGER': '/bin/cat', 04:46:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:07 INFO - 'PWD': '/builds/slave/test', 04:46:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:07 INFO - 'SHELL': '/bin/bash', 04:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lA3pPYZsOj/Listeners', 04:46:07 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 04:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:07 INFO - 'USER': 'cltbld', 04:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:07 INFO - 'XPC_FLAGS': '0x0', 04:46:07 INFO - 'XPC_SERVICE_NAME': '0', 04:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:07 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/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--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 04:46:07 INFO - mozversion application_buildid: 20160512025507 04:46:07 INFO - mozversion application_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 04:46:07 INFO - mozversion application_display_name: Nightly 04:46:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:46:07 INFO - mozversion application_name: Firefox 04:46:07 INFO - mozversion application_remotingname: firefox 04:46:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:46:07 INFO - mozversion application_vendor: Mozilla 04:46:07 INFO - mozversion application_version: 49.0a1 04:46:07 INFO - mozversion platform_buildid: 20160512025507 04:46:07 INFO - mozversion platform_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 04:46:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:46:07 INFO - mozversion platform_version: 49.0a1 04:46:07 INFO - using testdate: 1463053567 04:46:07 INFO - actual date: 1463053567 04:46:07 INFO - starting webserver on 'localhost:49270' 04:46:07 INFO - SUITE-START | Running 2 tests 04:46:07 INFO - TEST-START | dromaeo_css 04:46:07 INFO - operating with platform_type : mac_ 04:46:07 INFO - Initialising browser for dromaeo_css test... 04:46:07 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpz_ZeiX/profile http://localhost:49270/getInfo.html) 04:46:10 INFO - PROCESS | 1659 | __metrics Screen width/height:1024/768 04:46:10 INFO - PROCESS | 1659 | colorDepth:24 04:46:10 INFO - PROCESS | 1659 | Browser inner width/height: 1024/605 04:46:10 INFO - PROCESS | 1659 | __metrics 04:46:10 INFO - PROCESS | 1659 | 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" 04:46:10 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:46:10 INFO - TEST-INFO | 1659: exit 0 04:46:10 INFO - Browser initialized. 04:46:10 INFO - Running cycle 1/1 for dromaeo_css test... 04:46:10 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpz_ZeiX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:22 INFO - PROCESS | 1662 | RSS: Main: 238436352 04:46:22 INFO - PROCESS | 1662 | 04:47:14 INFO - PROCESS | 1662 | 1463053634207 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] 04:49:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 04:49:26 INFO - PROCESS | 1662 | RSS: Main: 377278464 04:49:26 INFO - PROCESS | 1662 | 04:52:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 04:52:29 INFO - PROCESS | 1662 | RSS: Main: 365707264 04:52:29 INFO - PROCESS | 1662 | 04:55:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 04:55:32 INFO - PROCESS | 1662 | RSS: Main: 364515328 04:55:32 INFO - PROCESS | 1662 | 04:58:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 04:58:35 INFO - PROCESS | 1662 | RSS: Main: 365191168 04:58:35 INFO - PROCESS | 1662 | 05:01:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:01:39 INFO - PROCESS | 1662 | RSS: Main: 332632064 05:01:39 INFO - PROCESS | 1662 | 05:04:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:04:42 INFO - PROCESS | 1662 | RSS: Main: 373735424 05:04:42 INFO - PROCESS | 1662 | 05:04:42 INFO - PROCESS | 1662 | __start_tp_report 05:04:42 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 05:04:42 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 05:04:42 INFO - PROCESS | 1662 | |i|pagename|runs| 05:04:42 INFO - PROCESS | 1662 | |0;dojo.html;3357.4963755436684;3407.539982102417;3427.8628854845806;3432.725381969443;3477.6000759912604;5618.213450116987;5621;5664.518515926141;5666.801661941838;5692.146178073286;5643.8024669136485;5708.571857110723;5768.942310576893;5772.538196944245;5785.595008349417;5096.79612815487;5119.308893299394;5192.55863251624;5215.86960325993;5291.179867120583;5271.551918086967;5317.255584218213;5319.042572336978;5361.785528578852;5370.650907691007;5137.845864624067;5170.3278573785165;5187.04039752647;5191.117510023305;5261.579073674096;5156.587473002189;5192.273081768555;5206.401263854629;5208.348956380452;5222.373315202162;5185.766640501187;5193;5194.168932970707;5200.583953283728;5230.215467679878;5498.1752737089355;5510;5599.300087489064;5663.745131469093;5684.943150568524;6128.717018009216;6144.477719393857;6180.969095154495;6185.041318595625;6187.288461826903;5165.535101840853;6135.601185922946;6177.69111544421;6215.906761398583;6220.315765265817;5641.18202860587;5690.800821971211;5751.913998968619;5752.108423194411;5819.359870414293;6238.812835614939;6274.247090349096;6288.213973253343;6291.905621415683;6297.937020629826;6159.291681456601;6170.598911070766;6183.783567575113;6204.162438070893;6221.906671399933;6079.544034197453;6165.506759459322;6169.753209871645;6255.093011513396;6296.527760417987;6161.229846269216;6175.104609831549;6223.222097237845;6225.3774622537385;6250.093736408196;6225.750969961151;6231.3145553989025;6254.6247225166635;6261.092141639437;6261.342559031327;4803.327534145223;4806.711597304173;4859.586935110485;4930.309756634074;4964.826231081895;4391.6486681065435;4448.888777780581;4452.888677783081;4457.732536047847;4459.487158976759;5709.22925405065;5721.199032135504;5723.227364305849;5726.484616384545;5732.140178973104;6156.907646385308;6208.410201030985;6214.1921550198185;6220.875582488325;6234.407731265523;5760.452756988079;5790.536757059425;5813.447722466359;5817.767289308465;5818.8254352369495;5397;5409.486098820606;5414.675119492843;5426.620136590401;5434.755436005389;6193.752249909953;6222.502199824004;6250.1874756282305;6281.12064311006;6324.7153878076515;6264.185655864709;6270.780522681866;6291.213598300213;6303.833790748726;6323.051542268697;6180.381961803783;6291.685415729286;6311.589746666452;6317.842053948688;6323.146375239284;6118.877622447615;6141.201643786279;6148.800983808114;6212.565120441525;6223.471004964629;6099.512039036777;6262.874742505215;6275.246970363403;6290.496760259077;6305.5270854687;5907.40925907423;5947.881042379042;6107.511399087974;6110.114033465034;6126.6017708848785;6018.638881667114;6046.788362407295;6055.788047418319;6069.2110025696375;6082.574219804571;5955.970220148872;5973.133895585029;5990.101484777319;6022.45797878193;6090.850291059186;5138.948610513847;6135.294441139326;6142.723577438936;6149.292831324455;6158.969205153946;5431.8098866539485;5766.106253530709;5883.264591926009;5884;5973.731182096903 05:04:42 INFO - PROCESS | 1662 | |1;ext.html;516.9793208271667;521.1011006014626;529.2696079410413;531.0176174077959;543.6710789972066;23919.641205381933;29885.252868678268;44395.890102747406;44889.511048895096;45409.31886021704;44177.77911110448;44296;44503.887402814944;44548;44559.88600284995;9824.950875245631;10015.89984100157;10031.949840250789;10032.448215348153;10039.04629060238;8804.559772011406;9016.413933094327;12050.216735912181;12281.324527151002;12298.077644176723;10253.436061016679;12054.819177712341;12135.817962730565;12138.939305303506;12160.817587736192;8847.911520884772;9075.954620226923;11945.163838531307;12072.154949153562;12101.27392356457;8749.650013999433;8818.030016698158;11676.241044332091;11997.760044799143;12017.93991030048;34764.65235347639;35152.76965306228;42684;42736;42847.7857610713;3193.8083714977174;3320.435525960568;3442.1566716154703;3517.5954765202073;3562.982185089058;29481.70518294811;29572.8521357394;29637.85181074081;30124.69875301241;30515.694843051508;2701.3651791828906;2712.4846279207086;2733.1937078561873;2743.2867454461784;2825.759810416117;460450;460849;461058;461603;462063;36081.63918360835;37005.88982330534;37042.8147859259;38125.2374952505;38199;37724.62275377211;37897.431538526944;38017.809910950265;38036.85889423322;38116;14421.92789036048;14559.927200364142;14576.489822856149;14630.561083167522;14660.633484162981;11444.771104578029;11856.585019524275;12268.570600029134;12396.938015309866;12527.87472125267;8251.174882511701;8332.958335208285;8349.624266908004;8403.621837017348;8408.663653453916;3465.7920524768597;3471.2536804587135;3480.1821571930577;3480.3735327641266;3507.175813683782;973.8539219117189;1012.7923775626126;1013.2096964367805;1136.732867776072;1139.3676509537286;29190.708092918798;30104.548431773543;30300.393992120033;30845.69154308473;30899.536506952412;907.7957459571676;1101.7410908436511;1116.905063069632;1131.7510147767478;1202.1765090912752;14748.57219680042;14787.408503659948;15123.31945062475;15716.528504144893;15957.521274361789;179684.20315796675;181958;182156;182233.08883455762;182611;61979.690101549204;62534.68732656308;62745.37254627486;62964.370356296764;62969.685151574864;1317.157019507516;1432.2910159471337;1469.860363265488;1472.5950363650081;1485.9405623775144;31090.795553890344;31582.04342082287;31969.68030319667;32165.678343216267;33365.66634333723;53076.20385694141;53148.73425632847;53511.19733203927;53551.73224133854;53693;440890;440943.79528102156;441569;446026;447197;27631.30921726973;27759;28910;28965.85517072401;29718.108456746333;39339.80330098446;46653;47110.05779884315;47257.52742472531;49022.26466603075;37973.430398544595;39533.80233098816;39617;40354.79822600868;40507.59492405038;37544.43683344807;38388.232235354575;38461.80769096137;39118.02204944899;39639.20721585494;37318.25363492661;38547.42178867375;39374.80312598419;39794.80102599468;39879.601203987586;28987.27531811637;29757.21456712604;34477.655223447444;34501.482477763355;34533.48199776955;21927.12291508322;24747.62878556787;25164.74835251624;25400.872995635644;25846.870765646803 05:04:42 INFO - PROCESS | 1662 | |2;jquery.html;383.8176865988655;387.6433681013468;394.96247856453647;397.74083207382057;412.5338367644561;16421.589460263483;20547.280845170422;21695.45761355967;21705.565888682217;21719.67420488692;15752.054876707405;16834.915825420856;20253;20847;21147.894260528716;5211.5830733541225;5927.140564618117;6054.243219597551;6139.293981192175;6163.907541386883;6074.301455332649;6081.11823785553;6096.055111457731;6119.296280927683;6124.908126378108;5732.2548068751;6099.420555047263;6100.572959892809;6103.908441373383;6107;5956.285245770514;6023.518118550527;6042.274927008743;6277.18396608438;6592.80221593353;5684.943150568482;5827.038538641119;5867.11993201019;5871.588988770745;5873;13514.797278040838;18578.53553661156;22142.667859982113;25564.48871022569;25593.488130237292;2027.9898600507051;2134.445044288481;2190.7809219077963;2193;2259.672347509602;9039.909600904039;9202.03378645246;9621.08599683029;9922.40465572068;10039.74900627483;1723.8534724548429;1734.2015922492053;1742.198588649223;1742.7037403641411;1839.7792264928294;94784.52607736917;129954;131122;131554.02668959973;131972;9440.527973601293;10008.099271065494;10105.44420056904;10297;10411.53148108337;12569.183003104768;12593.433295501725;12663;12677.30274834893;12711.936440317739;6617.768378106839;7943.165967573437;8109.270165685117;8208.015038195455;8223.95888020568;8315.95842020786;8324.70863519774;8340.874886876702;8458.323334133378;8476.830463390821;3308.1894935740897;3989.8005099744896;4023.436718859362;4073.103917138284;4073.246449406869;1685.2753316074206;1947.2053538232742;1969.8916559589074;1976.1206263212732;2008.2167954497756;700.2439943663081;723.7720118162867;930.0652843891845;943.6555657185078;1020.9438480883623;19986.60026799456;20233.49416264576;20883;21011.84434856067;21438.46403839885;758.893754874318;880.2693764175718;910.6084383714933;964.363520076746;977.2328721953232;10112.191024718006;10460.790784184273;10912;10921.945390273157;10945.507452164671;531178;532980;534372;600921;604459;124716.37641811733;129418;129953;133472;145534.2723286398;898.2364989758784;1005.5424781724298;1007.7480629842539;1012.4431562640518;1040.0639424517994;17568.648627027644;17762.200700968748;17994.830336028117;18505;19455.70816437726;25558.61662075033;45615;49887.752806180135;50854.237186441496;52843.20735188898;78897;80617;82576;82840;83877.41207690982;6635.933640663531;8116.715914942949;8149.388795840454;8245.546494942835;8608.225259726654;8489.40574159803;12074.45664945097;12512.687182820502;12854.67863303425;12937.805932910826;5561.304836895388;6905.723771049102;10784.43625674257;11001.944990274997;11371.601993930173;10902.454877255946;10903.727406814893;10962.890371096506;11079.667609971713;11129;10720.678379648623;10985.945070274598;11162.832557511481;11241.60654377093;11501.48243329069;7034.613096279753;7443.920631508509;8112.148224436585;8309.875351869607;8521.488710677379;10466.215033872642;11124.443777810915;11382.54469821231;11430.314181149157;11462.598809041978 05:04:42 INFO - PROCESS | 1662 | |3;mootools.html;1417.8440371559134;1596.3853916242251;1618.4982655376834;1848.8983105929176;1965.1451618545925;8129.593520323982;8942.55287235638;9219.723408297745;9239.676611318606;9369.250459963203;7881.487703299296;8957.731268061953;9115.042920493352;9227.95386023069;9228.307876909243;2421.6246481795306;2457.5453541094953;2458.6312053192073;2458.6557881896547;2463.9260822175365;2391.2467572714627;2469.6172093325563;2472.6909136357954;2473.4434752180705;2480.60310350344;2426.6360045993065;2453.6319552067152;2454.644076608891;2455.70531536215;2466.6793316868784;2381.8451800633075;2415.492746523232;2423.2245681381964;2426.696662917135;2430.185887727596;2318.8840557972035;2395.904163833462;2401.9399515012265;2402.447437089462;2403.399150212447;8118.391120665974;8399.412041157122;9171.678991235274;9251.67619133327;9254.2133918617;5268.0517506848755;5500.477454641843;5501.779928802883;5528.9723551382385;5536.114221724545;3005.962942784736;3439.1402149462633;3622.040159357772;3656.1225305926387;3720;950.1353768071091;999.6101520406975;1014.8426993815971;1038.1746511523356;1039.8232300508932;12397.00823934083;12517.248965062034;12517.93741031289;12519.56181533642;12536.122471427003;4031.858884939013;4045.624487674146;4663.27719203524;4721.551452611996;4854.781534830941;9665.90334096664;9678.225741940629;9685.128338449433;9687.273454490942;9700.27247956392;5410.269613602192;5449.427810079964;5490;5502.202180683778;5514.751836167382;4927.137750893579;5040.092783298968;5148.433672296044;5156.613254005964;5165.819196328111;4096.877093687194;4177.832817337461;4186.225548273579;4238.3430568261965;4238.385434112037;1117.3016864459712;1356.19984209315;1356.891448684103;1372.9313534323244;1380.4064252371577;801.2468279816953;859.0636206534907;870.6256309786852;936.8454205056225;953.4374718915734;5778.624389414675;5855.180274761537;5857.26784151981;5959.195508606283;6148.73327711219;451.11356185095786;455.70151550734323;465.0350522665483;465.7694441251602;468.44957175318865;3398.9150271243416;3856.2287542491613;3922.980385098056;4042.757434553936;4061.735987160825;9984.900150998397;9986.500674966223;10266.691999240034;10277;10397.428141452141;10182.083612474913;10212.489375531193;10300.124489418333;10325.32885362449;10374.481275936323;6725.6973436195485;7785.104712957969;7860.803479912934;7964.681412743425;7965.8406831864195;5413.53984911287;5530.585206109477;5542.9168562472405;5599.524040456444;5746.224259725051;9301.72094837156;9421.340506164777;9568.66509672186;9661.3237073407;9704.563294651616;8603.35474839402;9765.121139097515;9795.755106122118;9828.705138845846;9886.950565247127;7305.3425191732995;7323.340899319087;7385.9630701846145;7396.55620662762;7404.444666649915;7708.190639982721;7882.802929926797;7890.566018869017;7941.523508589503;7948.165442628672;7118.750843720445;7197.856042879009;7219.711211551479;7224.89162662571;7365.226651201761;7071.116110486189;7079.68141433647;7084.149902011585;7191.568505889663;7215.819604509929;6548.508861835287;6667.133272674521;6672.299408562031;6717.193936727624;6729.1252137221845;3837.731358804857;4757.928631070467;4789.4971028041555;4858.611311095175;5165.251038599457;4900.705957642553;4970.27930950003;5012.323336349692;5047.747612619428;5283.260343551982 05:04:42 INFO - PROCESS | 1662 | |4;prototype.html;316.4778116108421;318.02525260077874;328.3007194675342;329.36925787117656;332.1728895051323;4665.580097791198;5285.119609812652;5329.587659270296;5416.968636761205;5472.783522673324;4504.68008975378;5323.589248849058;5393.085454663587;5398.811041613544;5423.918641220375;3581.6239294874053;3839.6352346527037;3875.457435958968;3942.7831469269177;4021.477207962975;3406.5401170841865;4003.259397011562;4086.734362266458;4094.8566800162002;4110.362893751473;3429.777064490813;4065.715399922007;4072.816723247446;4096.344584866422;4103.876883693494;3090.176795690531;3837.6546110850018;3980.960190398088;4018.859339923103;4108.5275193352845;3119.4385010698065;3295.9011229663147;3544.1848374873666;3544.627814079536;3560.786352818839;4205.474315710536;4264.8249392809585;4567.173130747694;5009.824656137054;5155.453363990788;4402.449693788277;4492.9550704493195;4649.395578574764;4667.603253723438;4681.361523466793;4228.703990720651;4918.434380046305;5233.555147812441;5327.30745003151;5548.975808184549;3908;3916.6279203475624;3925.8233379497706;3932.997085743117;3954.9123990902444;4988.401391833003;5131.255373173139;5245.583692403106;5732.767454997195;6025.8192254232445;2841.4033053058674;3243.4810430331036;3736.5516138063062;3858.2283543291455;3937.9564415429936;3356.3287342531103;3613.512175856226;3936.37018077112;3945.546262179829;3965.1054730047226;4104.789087219217;4446.199684056901;4601.332806743004;4628.467726211529;4659.534046595313;5435.103207970719;5559.138333558305;5569.637973531708;5581.302337207849;5588.776448941997;1221.4182633489652;1268.4989429175423;1315.7368925831117;1328.5416531296687;1340.383423625124;656.8505116046917;715.3669002932443;782.7827727883083;816.8529664660299;828.2193284438385;4363.781810909441;5104.719240441737;5187.481251874782;5236.973815130977;5247.9737601311745;4902.7058376497525;4958.380202474691;5812.38969908162;5830.708464576839;5831.329397119301;4460.907077765968;4847.884986453065;4869.440014398318;4985.651004429655;5316.973415132899;2601.8829152688177;2860.685324614291;3059.2351912021995;3065.417570661567;3108.689131086873;5810.767569297181;6099.451049405575;6106.702380714364;6130.080487926846;6132.50939924805;5640.041192997201;5924.8815023700145;5939.396362981971;6009.729562169713;6013.5567463808375;5293.761780719877;5693.889691510199;5798.362180160263;5999.850003749942;6061.515078793598;5410.567154627699;5622.413689702619;5832.766689332379;5884.440268483224;5885.676287804212;4642.837500687595;5632.464915832989;5796.59423840344;5804.2744656917885;5840.386759390203;6101.84745381369;6111.541634377529;6129.448349648553;6143.477804386677;6187.938120618916;3569.7322700798063;3685.9262814744093;3915.1190982028816;3918.392649139388;3933.4296527002853;4982.501749824988;5866.064198814466;5867.037146241121;5873.119032145041;5884.58807883444;5464;5471.2887324647545;5647.5481961442165;5654.293213348332;5672.262605861211;4642.073539481077;5034.546890779848;5403.9459605403445;5651.802186923438;5674.716264186856;4692.554207350297;4931.471243914398;5318;5574.9442505574425;5654.7455364509515;4723.008168284561;5111.667741596784;5507.504324610804;5701.800436984686;5737.565608597851;4996.100701873697;5066.949330506648;5542.334919809648;5556.972215138899;5575.944240557705 05:04:42 INFO - PROCESS | 1662 | |5;yui.html;662.2450406536549;683.6445048574744;698.2598445647614;737.8524295140974;738.6048464071721;14733.631659208526;15062.77405838912;15436.073835569874;15495.92252038741;15832.366705331802;11974.940125299361;14322.57032289033;14479.493217737381;14492.420303187886;14509.347079381427;733.0982891044019;748.3040772081962;900.819836032796;907.3104440625123;928.679605536089;911.5168960450972;917.0691747875878;917.1653795046509;918.4626993208981;921.262989608314;964.1178321835512;965.2037069417723;966.7438128895848;967.1585720423207;967.6226271754022;752.642494814964;925.4586067150725;946.6166202687854;946.9905300946971;958.8561715742625;714.2999860137095;715.2454160860291;715.3132992327372;716.4447553146301;716.8637958787814;14701.970862039661;14748.336324865406;14750.409983600643;14763.261836908112;14768.55694329161;10140.594376224943;12067.275963442222;12163.51345946161;12222.61588131683;12443.813342799865;10754.300970436896;10785.245032847704;10839.13286937043;10878.619248326271;11242.775144497064;4016.297147999118;4089.20260549193;4147.958520414818;4156.625903668684;4159.667226621863;72492.27507724961;73320.26679733241;73444;75019;75305.24694753037;9276.582553785096;9375.561994205134;9480;9683.273754468442;9804.950975245078;20875;20944.266950656653;20977.475563111046;21005.579888402146;21012.26457073995;7460.291272329228;7545.811354716176;7574.166841647414;7622.695092196361;7719.421043421766;4068.3694027425795;4099.262132816062;4101.794910254535;4119.217348703737;4409.779511024436;3535.116220944764;3626.546681664792;3632.3461776880417;3641.289948460078;3644.325799727059;554.7919530176184;579.6493121661418;640.1166390381244;650.853557949467;651.9706613202417;405.41620067103275;546.1643685161708;568.5366426362501;609.2201981463734;612.9478994285536;6337.6197428154455;7697.923020769721;8192.385571082194;8447.746567602982;8692.52191129494;579.3771695427432;586.6597373523347;593.5014587746227;602.4818655955875;606.4844881850391;3635.7454978151277;3727.235916637083;3783.848646054182;3838.654521093115;4087.89780255496;69115;69424;70046.64976675084;98756.01243987613;100228;72086;75927.24072759201;83168.58415708004;83803;85078.57460712781;71606.64196679191;74559.62720186473;75343.24656753472;77092.2290777085;78439.43121137659;8056.355491560544;8223.917760822314;8334.458260213067;8368.497890126611;8460.957695211484;45617.54382456266;46340.536594633624;47335.28997064978;47554.76222618865;47608.52391476041;63893.68053159704;65490.36274092995;66391.33608663852;67235.64675236538;68438.65780671065;10950.780984380428;11275.730432395952;11334.886651133384;11550.480228389575;12105.939470302592;11533;12015.579454719043;12062.896266837546;12202.511899523919;12401.751964960831;11280;11344;11457.942710286396;11568.24806387582;11918.284902905853;11317.434128293717;11455.083593312682;11659.708507287385;12398.25610463375;12469.812952805896;10965.232433729563;11090.279131856403;11172.776544469227;11482.885171148182;11571.19001669875;9290.303227258119;9312.48781317007;9417.246620270498;9427.198688111588;9430.10414010668;9478.431294122374;9514.619415223313;9548.092931171528;9556.76108097303;9882.160016398399 05:04:42 INFO - PROCESS | 1662 | __end_tp_report 05:04:42 INFO - PROCESS | 1662 | __start_cc_report 05:04:42 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1362 05:04:42 INFO - PROCESS | 1662 | __end_cc_report 05:04:42 INFO - PROCESS | 1662 | __startTimestamp1463054682679__endTimestamp 05:04:42 INFO - PROCESS | 1662 | 05:04:42 INFO - PROCESS | 1662 | ------- Summary: start ------- 05:04:42 INFO - PROCESS | 1662 | Number of tests: 6 05:04:42 INFO - PROCESS | 1662 | 05:04:42 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5728.26 Median:5947.88 stddev:629.20 (10.6%) stddev-sans-first:603.01 05:04:42 INFO - PROCESS | 1662 | Values: 3357.5 3407.5 3427.9 3432.7 3477.6 5618.2 5621.0 5664.5 5666.8 5692.1 5643.8 5708.6 5768.9 5772.5 5785.6 5096.8 5119.3 5192.6 5215.9 5291.2 5271.6 5317.3 5319.0 5361.8 5370.7 5137.8 5170.3 5187.0 5191.1 5261.6 5156.6 5192.3 5206.4 5208.3 5222.4 5185.8 5193.0 5194.2 5200.6 5230.2 5498.2 5510.0 5599.3 5663.7 5684.9 6128.7 6144.5 6181.0 6185.0 6187.3 5165.5 6135.6 6177.7 6215.9 6220.3 5641.2 5690.8 5751.9 5752.1 5819.4 6238.8 6274.2 6288.2 6291.9 6297.9 6159.3 6170.6 6183.8 6204.2 6221.9 6079.5 6165.5 6169.8 6255.1 6296.5 6161.2 6175.1 6223.2 6225.4 6250.1 6225.8 6231.3 6254.6 6261.1 6261.3 4803.3 4806.7 4859.6 4930.3 4964.8 4391.6 4448.9 4452.9 4457.7 4459.5 5709.2 5721.2 5723.2 5726.5 5732.1 6156.9 6208.4 6214.2 6220.9 6234.4 5760.5 5790.5 5813.4 5817.8 5818.8 5397.0 5409.5 5414.7 5426.6 5434.8 6193.8 6222.5 6250.2 6281.1 6324.7 6264.2 6270.8 6291.2 6303.8 6323.1 6180.4 6291.7 6311.6 6317.8 6323.1 6118.9 6141.2 6148.8 6212.6 6223.5 6099.5 6262.9 6275.2 6290.5 6305.5 5907.4 5947.9 6107.5 6110.1 6126.6 6018.6 6046.8 6055.8 6069.2 6082.6 5956.0 5973.1 5990.1 6022.5 6090.9 5138.9 6135.3 6142.7 6149.3 6159.0 5431.8 5766.1 5883.3 5884.0 5973.7 05:04:42 INFO - PROCESS | 1662 | 05:04:42 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:51620.41 Median:18999.64 stddev:102255.71 (538.2%) stddev-sans-first:102470.58 05:04:42 INFO - PROCESS | 1662 | Values: 517.0 521.1 529.3 531.0 543.7 23919.6 29885.3 44395.9 44889.5 45409.3 44177.8 44296.0 44503.9 44548.0 44559.9 9825.0 10015.9 10031.9 10032.4 10039.0 8804.6 9016.4 12050.2 12281.3 12298.1 10253.4 12054.8 12135.8 12138.9 12160.8 8847.9 9076.0 11945.2 12072.2 12101.3 8749.7 8818.0 11676.2 11997.8 12017.9 34764.7 35152.8 42684.0 42736.0 42847.8 3193.8 3320.4 3442.2 3517.6 3563.0 29481.7 29572.9 29637.9 30124.7 30515.7 2701.4 2712.5 2733.2 2743.3 2825.8 460450.0 460849.0 461058.0 461603.0 462063.0 36081.6 37005.9 37042.8 38125.2 38199.0 37724.6 37897.4 38017.8 38036.9 38116.0 14421.9 14559.9 14576.5 14630.6 14660.6 11444.8 11856.6 12268.6 12396.9 12527.9 8251.2 8333.0 8349.6 8403.6 8408.7 3465.8 3471.3 3480.2 3480.4 3507.2 973.9 1012.8 1013.2 1136.7 1139.4 29190.7 30104.5 30300.4 30845.7 30899.5 907.8 1101.7 1116.9 1131.8 1202.2 14748.6 14787.4 15123.3 15716.5 15957.5 179684.2 181958.0 182156.0 182233.1 182611.0 61979.7 62534.7 62745.4 62964.4 62969.7 1317.2 1432.3 1469.9 1472.6 1485.9 31090.8 31582.0 31969.7 32165.7 33365.7 53076.2 53148.7 53511.2 53551.7 53693.0 440890.0 440943.8 441569.0 446026.0 447197.0 27631.3 27759.0 28910.0 28965.9 29718.1 39339.8 46653.0 47110.1 47257.5 49022.3 37973.4 39533.8 39617.0 40354.8 40507.6 37544.4 38388.2 38461.8 39118.0 39639.2 37318.3 38547.4 39374.8 39794.8 39879.6 28987.3 29757.2 34477.7 34501.5 34533.5 21927.1 24747.6 25164.7 25400.9 25846.9 05:04:42 INFO - PROCESS | 1662 | 05:04:42 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33763.68 Median:25578.99 stddev:94567.86 (369.7%) stddev-sans-first:94799.93 05:04:42 INFO - PROCESS | 1662 | Values: 383.8 387.6 395.0 397.7 412.5 16421.6 20547.3 21695.5 21705.6 21719.7 15752.1 16834.9 20253.0 20847.0 21147.9 5211.6 5927.1 6054.2 6139.3 6163.9 6074.3 6081.1 6096.1 6119.3 6124.9 5732.3 6099.4 6100.6 6103.9 6107.0 5956.3 6023.5 6042.3 6277.2 6592.8 5684.9 5827.0 5867.1 5871.6 5873.0 13514.8 18578.5 22142.7 25564.5 25593.5 2028.0 2134.4 2190.8 2193.0 2259.7 9039.9 9202.0 9621.1 9922.4 10039.7 1723.9 1734.2 1742.2 1742.7 1839.8 94784.5 129954.0 131122.0 131554.0 131972.0 9440.5 10008.1 10105.4 10297.0 10411.5 12569.2 12593.4 12663.0 12677.3 12711.9 6617.8 7943.2 8109.3 8208.0 8224.0 8316.0 8324.7 8340.9 8458.3 8476.8 3308.2 3989.8 4023.4 4073.1 4073.2 1685.3 1947.2 1969.9 1976.1 2008.2 700.2 723.8 930.1 943.7 1020.9 19986.6 20233.5 20883.0 21011.8 21438.5 758.9 880.3 910.6 964.4 977.2 10112.2 10460.8 10912.0 10921.9 10945.5 531178.0 532980.0 534372.0 600921.0 604459.0 124716.4 129418.0 129953.0 133472.0 145534.3 898.2 1005.5 1007.7 1012.4 1040.1 17568.6 17762.2 17994.8 18505.0 19455.7 25558.6 45615.0 49887.8 50854.2 52843.2 78897.0 80617.0 82576.0 82840.0 83877.4 6635.9 8116.7 8149.4 8245.5 8608.2 8489.4 12074.5 12512.7 12854.7 12937.8 5561.3 6905.7 10784.4 11001.9 11371.6 10902.5 10903.7 10962.9 11079.7 11129.0 10720.7 10985.9 11162.8 11241.6 11501.5 7034.6 7443.9 8112.1 8309.9 8521.5 10466.2 11124.4 11382.5 11430.3 11462.6 05:04:42 INFO - PROCESS | 1662 | 05:04:42 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5642.39 Median:5275.66 stddev:3159.47 (59.9%) stddev-sans-first:3152.38 05:04:42 INFO - PROCESS | 1662 | Values: 1417.8 1596.4 1618.5 1848.9 1965.1 8129.6 8942.6 9219.7 9239.7 9369.3 7881.5 8957.7 9115.0 9228.0 9228.3 2421.6 2457.5 2458.6 2458.7 2463.9 2391.2 2469.6 2472.7 2473.4 2480.6 2426.6 2453.6 2454.6 2455.7 2466.7 2381.8 2415.5 2423.2 2426.7 2430.2 2318.9 2395.9 2401.9 2402.4 2403.4 8118.4 8399.4 9171.7 9251.7 9254.2 5268.1 5500.5 5501.8 5529.0 5536.1 3006.0 3439.1 3622.0 3656.1 3720.0 950.1 999.6 1014.8 1038.2 1039.8 12397.0 12517.2 12517.9 12519.6 12536.1 4031.9 4045.6 4663.3 4721.6 4854.8 9665.9 9678.2 9685.1 9687.3 9700.3 5410.3 5449.4 5490.0 5502.2 5514.8 4927.1 5040.1 5148.4 5156.6 5165.8 4096.9 4177.8 4186.2 4238.3 4238.4 1117.3 1356.2 1356.9 1372.9 1380.4 801.2 859.1 870.6 936.8 953.4 5778.6 5855.2 5857.3 5959.2 6148.7 451.1 455.7 465.0 465.8 468.4 3398.9 3856.2 3923.0 4042.8 4061.7 9984.9 9986.5 10266.7 10277.0 10397.4 10182.1 10212.5 10300.1 10325.3 10374.5 6725.7 7785.1 7860.8 7964.7 7965.8 5413.5 5530.6 5542.9 5599.5 5746.2 9301.7 9421.3 9568.7 9661.3 9704.6 8603.4 9765.1 9795.8 9828.7 9887.0 7305.3 7323.3 7386.0 7396.6 7404.4 7708.2 7882.8 7890.6 7941.5 7948.2 7118.8 7197.9 7219.7 7224.9 7365.2 7071.1 7079.7 7084.1 7191.6 7215.8 6548.5 6667.1 6672.3 6717.2 6729.1 3837.7 4757.9 4789.5 4858.6 5165.3 4900.7 4970.3 5012.3 5047.7 5283.3 05:04:42 INFO - PROCESS | 1662 | 05:04:42 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4513.93 Median:4992.25 stddev:1428.69 (28.6%) stddev-sans-first:1397.53 05:04:42 INFO - PROCESS | 1662 | Values: 316.5 318.0 328.3 329.4 332.2 4665.6 5285.1 5329.6 5417.0 5472.8 4504.7 5323.6 5393.1 5398.8 5423.9 3581.6 3839.6 3875.5 3942.8 4021.5 3406.5 4003.3 4086.7 4094.9 4110.4 3429.8 4065.7 4072.8 4096.3 4103.9 3090.2 3837.7 3981.0 4018.9 4108.5 3119.4 3295.9 3544.2 3544.6 3560.8 4205.5 4264.8 4567.2 5009.8 5155.5 4402.4 4493.0 4649.4 4667.6 4681.4 4228.7 4918.4 5233.6 5327.3 5549.0 3908.0 3916.6 3925.8 3933.0 3954.9 4988.4 5131.3 5245.6 5732.8 6025.8 2841.4 3243.5 3736.6 3858.2 3938.0 3356.3 3613.5 3936.4 3945.5 3965.1 4104.8 4446.2 4601.3 4628.5 4659.5 5435.1 5559.1 5569.6 5581.3 5588.8 1221.4 1268.5 1315.7 1328.5 1340.4 656.9 715.4 782.8 816.9 828.2 4363.8 5104.7 5187.5 5237.0 5248.0 4902.7 4958.4 5812.4 5830.7 5831.3 4460.9 4847.9 4869.4 4985.7 5317.0 2601.9 2860.7 3059.2 3065.4 3108.7 5810.8 6099.5 6106.7 6130.1 6132.5 5640.0 5924.9 5939.4 6009.7 6013.6 5293.8 5693.9 5798.4 5999.9 6061.5 5410.6 5622.4 5832.8 5884.4 5885.7 4642.8 5632.5 5796.6 5804.3 5840.4 6101.8 6111.5 6129.4 6143.5 6187.9 3569.7 3685.9 3915.1 3918.4 3933.4 4982.5 5866.1 5867.0 5873.1 5884.6 5464.0 5471.3 5647.5 5654.3 5672.3 4642.1 5034.5 5403.9 5651.8 5674.7 4692.6 4931.5 5318.0 5574.9 5654.7 4723.0 5111.7 5507.5 5701.8 5737.6 4996.1 5066.9 5542.3 5557.0 5575.9 05:04:42 INFO - PROCESS | 1662 | 05:04:42 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17905.31 Median:607.85 stddev:24817.24 (4082.8%) stddev-sans-first:24853.09 05:04:42 INFO - PROCESS | 1662 | Values: 662.2 683.6 698.3 737.9 738.6 14733.6 15062.8 15436.1 15495.9 15832.4 11974.9 14322.6 14479.5 14492.4 14509.3 733.1 748.3 900.8 907.3 928.7 911.5 917.1 917.2 918.5 921.3 964.1 965.2 966.7 967.2 967.6 752.6 925.5 946.6 947.0 958.9 714.3 715.2 715.3 716.4 716.9 14702.0 14748.3 14750.4 14763.3 14768.6 10140.6 12067.3 12163.5 12222.6 12443.8 10754.3 10785.2 10839.1 10878.6 11242.8 4016.3 4089.2 4148.0 4156.6 4159.7 72492.3 73320.3 73444.0 75019.0 75305.2 9276.6 9375.6 9480.0 9683.3 9805.0 20875.0 20944.3 20977.5 21005.6 21012.3 7460.3 7545.8 7574.2 7622.7 7719.4 4068.4 4099.3 4101.8 4119.2 4409.8 3535.1 3626.5 3632.3 3641.3 3644.3 554.8 579.6 640.1 650.9 652.0 405.4 546.2 568.5 609.2 612.9 6337.6 7697.9 8192.4 8447.7 8692.5 579.4 586.7 593.5 602.5 606.5 3635.7 3727.2 3783.8 3838.7 4087.9 69115.0 69424.0 70046.6 98756.0 100228.0 72086.0 75927.2 83168.6 83803.0 85078.6 71606.6 74559.6 75343.2 77092.2 78439.4 8056.4 8223.9 8334.5 8368.5 8461.0 45617.5 46340.5 47335.3 47554.8 47608.5 63893.7 65490.4 66391.3 67235.6 68438.7 10950.8 11275.7 11334.9 11550.5 12105.9 11533.0 12015.6 12062.9 12202.5 12401.8 11280.0 11344.0 11457.9 11568.2 11918.3 11317.4 11455.1 11659.7 12398.3 12469.8 10965.2 11090.3 11172.8 11482.9 11571.2 9290.3 9312.5 9417.2 9427.2 9430.1 9478.4 9514.6 9548.1 9556.8 9882.2 05:04:42 INFO - PROCESS | 1662 | -------- Summary: end -------- 05:04:42 INFO - PROCESS | 1662 | 05:04:43 INFO - TEST-INFO | 1662: exit 0 05:04:43 INFO - TEST-OK | dromaeo_css | took 1115630ms 05:04:43 INFO - TEST-START | kraken 05:04:43 INFO - operating with platform_type : mac_ 05:04:43 INFO - Initialising browser for kraken test... 05:04:43 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpiRc71I/profile http://localhost:49270/getInfo.html) 05:04:45 INFO - PROCESS | 1670 | __metrics Screen width/height:1024/768 05:04:45 INFO - PROCESS | 1670 | colorDepth:24 05:04:45 INFO - PROCESS | 1670 | Browser inner width/height: 1024/605 05:04:45 INFO - PROCESS | 1670 | __metrics 05:04: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" 05:04:45 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:04:46 INFO - TEST-INFO | 1670: exit 0 05:04:46 INFO - Browser initialized. 05:04:46 INFO - Running cycle 1/1 for kraken test... 05:04:46 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpiRc71I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:58 INFO - PROCESS | 1672 | RSS: Main: 238612480 05:04:58 INFO - PROCESS | 1672 | 05:05:00 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:05:01 INFO - PROCESS | 1672 | RSS: Main: 285016064 05:05:01 INFO - PROCESS | 1672 | 05:05:04 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:05:05 INFO - PROCESS | 1672 | RSS: Main: 292438016 05:05:05 INFO - PROCESS | 1672 | 05:05:08 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:05:09 INFO - PROCESS | 1672 | RSS: Main: 381116416 05:05:09 INFO - PROCESS | 1672 | 05:05:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:05:12 INFO - PROCESS | 1672 | RSS: Main: 280252416 05:05:12 INFO - PROCESS | 1672 | 05:05:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:05:15 INFO - PROCESS | 1672 | RSS: Main: 284233728 05:05:15 INFO - PROCESS | 1672 | 05:05:18 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:05:18 INFO - PROCESS | 1672 | RSS: Main: 321912832 05:05:18 INFO - PROCESS | 1672 | 05:05:21 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:22 INFO - PROCESS | 1672 | RSS: Main: 325611520 05:05:22 INFO - PROCESS | 1672 | 05:05:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:05:26 INFO - PROCESS | 1672 | RSS: Main: 321982464 05:05:26 INFO - PROCESS | 1672 | 05:05:28 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:28 INFO - PROCESS | 1672 | RSS: Main: 312123392 05:05:28 INFO - PROCESS | 1672 | 05:05:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:31 INFO - PROCESS | 1672 | RSS: Main: 319987712 05:05:31 INFO - PROCESS | 1672 | 05:05:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:05:34 INFO - PROCESS | 1672 | RSS: Main: 320552960 05:05:34 INFO - PROCESS | 1672 | 05:05:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:05:38 INFO - PROCESS | 1672 | RSS: Main: 326463488 05:05:38 INFO - PROCESS | 1672 | 05:05:41 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:05:41 INFO - PROCESS | 1672 | RSS: Main: 323694592 05:05:41 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:05:43 INFO - PROCESS | 1672 | RSS: Main: 370937856 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | __start_tp_report 05:05:43 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 05:05:43 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 05:05:43 INFO - PROCESS | 1672 | |i|pagename|runs| 05:05:43 INFO - PROCESS | 1672 | |0;ai-astar;110;107;111;110;112;111;112;110;110;111 05:05:43 INFO - PROCESS | 1672 | |1;audio-beat-detection;151;118;138;196;116;139;135;117;171;117 05:05:43 INFO - PROCESS | 1672 | |2;audio-dft;185;184;186;185;184;184;184;181;183;184 05:05:43 INFO - PROCESS | 1672 | |3;audio-fft;78;74;57;124;57;61;57;59;109;56 05:05:43 INFO - PROCESS | 1672 | |4;audio-oscillator;92;102;151;109;114;81;103;80;79;102 05:05:43 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;89;84;88;85;87;85;88;85;87;85 05:05:43 INFO - PROCESS | 1672 | |6;imaging-darkroom;94;94;94;95;93;98;93;129;140;129 05:05:43 INFO - PROCESS | 1672 | |7;imaging-desaturate;83;83;84;84;86;83;102;132;84;83 05:05:43 INFO - PROCESS | 1672 | |8;json-parse-financial;58;58;58;57;57;57;58;130;94;89 05:05:43 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;63;72;75;69;71;68;65;67;68;67 05:05:43 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;85;86;85;83;83;86;100;104;107;83 05:05:43 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;137;136;158;148;149;144;143;147;227;142 05:05:43 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;168;167;169;167;167;170;169;169;175;169 05:05:43 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;66;67;71;80;84;86;85;67;67;65 05:05:43 INFO - PROCESS | 1672 | __end_tp_report 05:05:43 INFO - PROCESS | 1672 | __start_cc_report 05:05:43 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2277 05:05:43 INFO - PROCESS | 1672 | __end_cc_report 05:05:43 INFO - PROCESS | 1672 | __startTimestamp1463054743864__endTimestamp 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | ------- Summary: start ------- 05:05:43 INFO - PROCESS | 1672 | Number of tests: 14 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:1.43 (1.3%) stddev-sans-first:1.51 05:05:43 INFO - PROCESS | 1672 | Values: 110.0 107.0 111.0 110.0 112.0 111.0 112.0 110.0 110.0 111.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:138.50 stddev:26.59 (19.2%) stddev-sans-first:27.90 05:05:43 INFO - PROCESS | 1672 | Values: 151.0 118.0 138.0 196.0 116.0 139.0 135.0 117.0 171.0 117.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.00 stddev:1.33 (0.7%) stddev-sans-first:1.36 05:05:43 INFO - PROCESS | 1672 | Values: 185.0 184.0 186.0 185.0 184.0 184.0 184.0 181.0 183.0 184.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:73.20 Median:58.00 stddev:24.31 (41.9%) stddev-sans-first:25.72 05:05:43 INFO - PROCESS | 1672 | Values: 78.0 74.0 57.0 124.0 57.0 61.0 57.0 59.0 109.0 56.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:101.30 Median:115.00 stddev:21.46 (18.7%) stddev-sans-first:22.49 05:05:43 INFO - PROCESS | 1672 | Values: 92.0 102.0 151.0 109.0 114.0 81.0 103.0 80.0 79.0 102.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.70 (2.0%) stddev-sans-first:1.50 05:05:43 INFO - PROCESS | 1672 | Values: 89.0 84.0 88.0 85.0 87.0 85.0 88.0 85.0 87.0 85.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:105.90 Median:94.00 stddev:18.76 (20.0%) stddev-sans-first:19.40 05:05:43 INFO - PROCESS | 1672 | Values: 94.0 94.0 94.0 95.0 93.0 98.0 93.0 129.0 140.0 129.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:83.50 stddev:15.73 (18.8%) stddev-sans-first:16.45 05:05:43 INFO - PROCESS | 1672 | Values: 83.0 83.0 84.0 84.0 86.0 83.0 102.0 132.0 84.0 83.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:71.60 Median:58.00 stddev:24.93 (43.0%) stddev-sans-first:25.95 05:05:43 INFO - PROCESS | 1672 | Values: 58.0 58.0 58.0 57.0 57.0 57.0 58.0 130.0 94.0 89.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:68.50 stddev:3.47 (5.1%) stddev-sans-first:3.06 05:05:43 INFO - PROCESS | 1672 | Values: 63.0 72.0 75.0 69.0 71.0 68.0 65.0 67.0 68.0 67.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:84.00 stddev:9.51 (11.3%) stddev-sans-first:9.90 05:05:43 INFO - PROCESS | 1672 | Values: 85.0 86.0 85.0 83.0 83.0 86.0 100.0 104.0 107.0 83.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:147.50 stddev:26.72 (18.1%) stddev-sans-first:27.70 05:05:43 INFO - PROCESS | 1672 | Values: 137.0 136.0 158.0 148.0 149.0 144.0 143.0 147.0 227.0 142.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:169.00 stddev:2.36 (1.4%) stddev-sans-first:2.47 05:05:43 INFO - PROCESS | 1672 | Values: 168.0 167.0 169.0 167.0 167.0 170.0 169.0 169.0 175.0 169.0 05:05:43 INFO - PROCESS | 1672 | 05:05:43 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:75.50 stddev:8.83 (11.7%) stddev-sans-first:8.90 05:05:43 INFO - PROCESS | 1672 | Values: 66.0 67.0 71.0 80.0 84.0 86.0 85.0 67.0 67.0 65.0 05:05:43 INFO - PROCESS | 1672 | -------- Summary: end -------- 05:05:43 INFO - PROCESS | 1672 | 05:05:44 INFO - TEST-INFO | 1672: exit 0 05:05:44 INFO - TEST-OK | kraken | took 61339ms 05:05:44 INFO - SUITE-END | took 1176s 05:05:45 INFO - Completed test suite (00:19:37) 05:05:45 INFO - javascript benchmark 05:05:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3357.4963755436684, 3407.539982102417, 3427.8628854845806, 3432.725381969443, 3477.6000759912604, 5618.213450116987, 5621.0, 5664.518515926141, 5666.801661941838, 5692.146178073286, 5643.8024669136485, 5708.571857110723, 5768.942310576893, 5772.538196944245, 5785.595008349417, 5096.79612815487, 5119.308893299394, 5192.55863251624, 5215.86960325993, 5291.179867120583, 5271.551918086967, 5317.255584218213, 5319.042572336978, 5361.785528578852, 5370.650907691007, 5137.845864624067, 5170.3278573785165, 5187.04039752647, 5191.117510023305, 5261.579073674096, 5156.587473002189, 5192.273081768555, 5206.401263854629, 5208.348956380452, 5222.373315202162, 5185.766640501187, 5193.0, 5194.168932970707, 5200.583953283728, 5230.215467679878, 5498.1752737089355, 5510.0, 5599.300087489064, 5663.745131469093, 5684.943150568524, 6128.717018009216, 6144.477719393857, 6180.969095154495, 6185.041318595625, 6187.288461826903, 5165.535101840853, 6135.601185922946, 6177.69111544421, 6215.906761398583, 6220.315765265817, 5641.18202860587, 5690.800821971211, 5751.913998968619, 5752.108423194411, 5819.359870414293, 6238.812835614939, 6274.247090349096, 6288.213973253343, 6291.905621415683, 6297.937020629826, 6159.291681456601, 6170.598911070766, 6183.783567575113, 6204.162438070893, 6221.906671399933, 6079.544034197453, 6165.506759459322, 6169.753209871645, 6255.093011513396, 6296.527760417987, 6161.229846269216, 6175.104609831549, 6223.222097237845, 6225.3774622537385, 6250.093736408196, 6225.750969961151, 6231.3145553989025, 6254.6247225166635, 6261.092141639437, 6261.342559031327, 4803.327534145223, 4806.711597304173, 4859.586935110485, 4930.309756634074, 4964.826231081895, 4391.6486681065435, 4448.888777780581, 4452.888677783081, 4457.732536047847, 4459.487158976759, 5709.22925405065, 5721.199032135504, 5723.227364305849, 5726.484616384545, 5732.140178973104, 6156.907646385308, 6208.410201030985, 6214.1921550198185, 6220.875582488325, 6234.407731265523, 5760.452756988079, 5790.536757059425, 5813.447722466359, 5817.767289308465, 5818.8254352369495, 5397.0, 5409.486098820606, 5414.675119492843, 5426.620136590401, 5434.755436005389, 6193.752249909953, 6222.502199824004, 6250.1874756282305, 6281.12064311006, 6324.7153878076515, 6264.185655864709, 6270.780522681866, 6291.213598300213, 6303.833790748726, 6323.051542268697, 6180.381961803783, 6291.685415729286, 6311.589746666452, 6317.842053948688, 6323.146375239284, 6118.877622447615, 6141.201643786279, 6148.800983808114, 6212.565120441525, 6223.471004964629, 6099.512039036777, 6262.874742505215, 6275.246970363403, 6290.496760259077, 6305.5270854687, 5907.40925907423, 5947.881042379042, 6107.511399087974, 6110.114033465034, 6126.6017708848785, 6018.638881667114, 6046.788362407295, 6055.788047418319, 6069.2110025696375, 6082.574219804571, 5955.970220148872, 5973.133895585029, 5990.101484777319, 6022.45797878193, 6090.850291059186, 5138.948610513847, 6135.294441139326, 6142.723577438936, 6149.292831324455, 6158.969205153946, 5431.8098866539485, 5766.106253530709, 5883.264591926009, 5884.0, 5973.731182096903], "value": 5688.937046051466, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.9793208271667, 521.1011006014626, 529.2696079410413, 531.0176174077959, 543.6710789972066, 23919.641205381933, 29885.252868678268, 44395.890102747406, 44889.511048895096, 45409.31886021704, 44177.77911110448, 44296.0, 44503.887402814944, 44548.0, 44559.88600284995, 9824.950875245631, 10015.89984100157, 10031.949840250789, 10032.448215348153, 10039.04629060238, 8804.559772011406, 9016.413933094327, 12050.216735912181, 12281.324527151002, 12298.077644176723, 10253.436061016679, 12054.819177712341, 12135.817962730565, 12138.939305303506, 12160.817587736192, 8847.911520884772, 9075.954620226923, 11945.163838531307, 12072.154949153562, 12101.27392356457, 8749.650013999433, 8818.030016698158, 11676.241044332091, 11997.760044799143, 12017.93991030048, 34764.65235347639, 35152.76965306228, 42684.0, 42736.0, 42847.7857610713, 3193.8083714977174, 3320.435525960568, 3442.1566716154703, 3517.5954765202073, 3562.982185089058, 29481.70518294811, 29572.8521357394, 29637.85181074081, 30124.69875301241, 30515.694843051508, 2701.3651791828906, 2712.4846279207086, 2733.1937078561873, 2743.2867454461784, 2825.759810416117, 460450.0, 460849.0, 461058.0, 461603.0, 462063.0, 36081.63918360835, 37005.88982330534, 37042.8147859259, 38125.2374952505, 38199.0, 37724.62275377211, 37897.431538526944, 38017.809910950265, 38036.85889423322, 38116.0, 14421.92789036048, 14559.927200364142, 14576.489822856149, 14630.561083167522, 14660.633484162981, 11444.771104578029, 11856.585019524275, 12268.570600029134, 12396.938015309866, 12527.87472125267, 8251.174882511701, 8332.958335208285, 8349.624266908004, 8403.621837017348, 8408.663653453916, 3465.7920524768597, 3471.2536804587135, 3480.1821571930577, 3480.3735327641266, 3507.175813683782, 973.8539219117189, 1012.7923775626126, 1013.2096964367805, 1136.732867776072, 1139.3676509537286, 29190.708092918798, 30104.548431773543, 30300.393992120033, 30845.69154308473, 30899.536506952412, 907.7957459571676, 1101.7410908436511, 1116.905063069632, 1131.7510147767478, 1202.1765090912752, 14748.57219680042, 14787.408503659948, 15123.31945062475, 15716.528504144893, 15957.521274361789, 179684.20315796675, 181958.0, 182156.0, 182233.08883455762, 182611.0, 61979.690101549204, 62534.68732656308, 62745.37254627486, 62964.370356296764, 62969.685151574864, 1317.157019507516, 1432.2910159471337, 1469.860363265488, 1472.5950363650081, 1485.9405623775144, 31090.795553890344, 31582.04342082287, 31969.68030319667, 32165.678343216267, 33365.66634333723, 53076.20385694141, 53148.73425632847, 53511.19733203927, 53551.73224133854, 53693.0, 440890.0, 440943.79528102156, 441569.0, 446026.0, 447197.0, 27631.30921726973, 27759.0, 28910.0, 28965.85517072401, 29718.108456746333, 39339.80330098446, 46653.0, 47110.05779884315, 47257.52742472531, 49022.26466603075, 37973.430398544595, 39533.80233098816, 39617.0, 40354.79822600868, 40507.59492405038, 37544.43683344807, 38388.232235354575, 38461.80769096137, 39118.02204944899, 39639.20721585494, 37318.25363492661, 38547.42178867375, 39374.80312598419, 39794.80102599468, 39879.601203987586, 28987.27531811637, 29757.21456712604, 34477.655223447444, 34501.482477763355, 34533.48199776955, 21927.12291508322, 24747.62878556787, 25164.74835251624, 25400.872995635644, 25846.870765646803], "value": 18127.615734756484, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.8176865988655, 387.6433681013468, 394.96247856453647, 397.74083207382057, 412.5338367644561, 16421.589460263483, 20547.280845170422, 21695.45761355967, 21705.565888682217, 21719.67420488692, 15752.054876707405, 16834.915825420856, 20253.0, 20847.0, 21147.894260528716, 5211.5830733541225, 5927.140564618117, 6054.243219597551, 6139.293981192175, 6163.907541386883, 6074.301455332649, 6081.11823785553, 6096.055111457731, 6119.296280927683, 6124.908126378108, 5732.2548068751, 6099.420555047263, 6100.572959892809, 6103.908441373383, 6107.0, 5956.285245770514, 6023.518118550527, 6042.274927008743, 6277.18396608438, 6592.80221593353, 5684.943150568482, 5827.038538641119, 5867.11993201019, 5871.588988770745, 5873.0, 13514.797278040838, 18578.53553661156, 22142.667859982113, 25564.48871022569, 25593.488130237292, 2027.9898600507051, 2134.445044288481, 2190.7809219077963, 2193.0, 2259.672347509602, 9039.909600904039, 9202.03378645246, 9621.08599683029, 9922.40465572068, 10039.74900627483, 1723.8534724548429, 1734.2015922492053, 1742.198588649223, 1742.7037403641411, 1839.7792264928294, 94784.52607736917, 129954.0, 131122.0, 131554.02668959973, 131972.0, 9440.527973601293, 10008.099271065494, 10105.44420056904, 10297.0, 10411.53148108337, 12569.183003104768, 12593.433295501725, 12663.0, 12677.30274834893, 12711.936440317739, 6617.768378106839, 7943.165967573437, 8109.270165685117, 8208.015038195455, 8223.95888020568, 8315.95842020786, 8324.70863519774, 8340.874886876702, 8458.323334133378, 8476.830463390821, 3308.1894935740897, 3989.8005099744896, 4023.436718859362, 4073.103917138284, 4073.246449406869, 1685.2753316074206, 1947.2053538232742, 1969.8916559589074, 1976.1206263212732, 2008.2167954497756, 700.2439943663081, 723.7720118162867, 930.0652843891845, 943.6555657185078, 1020.9438480883623, 19986.60026799456, 20233.49416264576, 20883.0, 21011.84434856067, 21438.46403839885, 758.893754874318, 880.2693764175718, 910.6084383714933, 964.363520076746, 977.2328721953232, 10112.191024718006, 10460.790784184273, 10912.0, 10921.945390273157, 10945.507452164671, 531178.0, 532980.0, 534372.0, 600921.0, 604459.0, 124716.37641811733, 129418.0, 129953.0, 133472.0, 145534.2723286398, 898.2364989758784, 1005.5424781724298, 1007.7480629842539, 1012.4431562640518, 1040.0639424517994, 17568.648627027644, 17762.200700968748, 17994.830336028117, 18505.0, 19455.70816437726, 25558.61662075033, 45615.0, 49887.752806180135, 50854.237186441496, 52843.20735188898, 78897.0, 80617.0, 82576.0, 82840.0, 83877.41207690982, 6635.933640663531, 8116.715914942949, 8149.388795840454, 8245.546494942835, 8608.225259726654, 8489.40574159803, 12074.45664945097, 12512.687182820502, 12854.67863303425, 12937.805932910826, 5561.304836895388, 6905.723771049102, 10784.43625674257, 11001.944990274997, 11371.601993930173, 10902.454877255946, 10903.727406814893, 10962.890371096506, 11079.667609971713, 11129.0, 10720.678379648623, 10985.945070274598, 11162.832557511481, 11241.60654377093, 11501.48243329069, 7034.613096279753, 7443.920631508509, 8112.148224436585, 8309.875351869607, 8521.488710677379, 10466.215033872642, 11124.443777810915, 11382.54469821231, 11430.314181149157, 11462.598809041978], "value": 9228.020518664796, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.8440371559134, 1596.3853916242251, 1618.4982655376834, 1848.8983105929176, 1965.1451618545925, 8129.593520323982, 8942.55287235638, 9219.723408297745, 9239.676611318606, 9369.250459963203, 7881.487703299296, 8957.731268061953, 9115.042920493352, 9227.95386023069, 9228.307876909243, 2421.6246481795306, 2457.5453541094953, 2458.6312053192073, 2458.6557881896547, 2463.9260822175365, 2391.2467572714627, 2469.6172093325563, 2472.6909136357954, 2473.4434752180705, 2480.60310350344, 2426.6360045993065, 2453.6319552067152, 2454.644076608891, 2455.70531536215, 2466.6793316868784, 2381.8451800633075, 2415.492746523232, 2423.2245681381964, 2426.696662917135, 2430.185887727596, 2318.8840557972035, 2395.904163833462, 2401.9399515012265, 2402.447437089462, 2403.399150212447, 8118.391120665974, 8399.412041157122, 9171.678991235274, 9251.67619133327, 9254.2133918617, 5268.0517506848755, 5500.477454641843, 5501.779928802883, 5528.9723551382385, 5536.114221724545, 3005.962942784736, 3439.1402149462633, 3622.040159357772, 3656.1225305926387, 3720.0, 950.1353768071091, 999.6101520406975, 1014.8426993815971, 1038.1746511523356, 1039.8232300508932, 12397.00823934083, 12517.248965062034, 12517.93741031289, 12519.56181533642, 12536.122471427003, 4031.858884939013, 4045.624487674146, 4663.27719203524, 4721.551452611996, 4854.781534830941, 9665.90334096664, 9678.225741940629, 9685.128338449433, 9687.273454490942, 9700.27247956392, 5410.269613602192, 5449.427810079964, 5490.0, 5502.202180683778, 5514.751836167382, 4927.137750893579, 5040.092783298968, 5148.433672296044, 5156.613254005964, 5165.819196328111, 4096.877093687194, 4177.832817337461, 4186.225548273579, 4238.3430568261965, 4238.385434112037, 1117.3016864459712, 1356.19984209315, 1356.891448684103, 1372.9313534323244, 1380.4064252371577, 801.2468279816953, 859.0636206534907, 870.6256309786852, 936.8454205056225, 953.4374718915734, 5778.624389414675, 5855.180274761537, 5857.26784151981, 5959.195508606283, 6148.73327711219, 451.11356185095786, 455.70151550734323, 465.0350522665483, 465.7694441251602, 468.44957175318865, 3398.9150271243416, 3856.2287542491613, 3922.980385098056, 4042.757434553936, 4061.735987160825, 9984.900150998397, 9986.500674966223, 10266.691999240034, 10277.0, 10397.428141452141, 10182.083612474913, 10212.489375531193, 10300.124489418333, 10325.32885362449, 10374.481275936323, 6725.6973436195485, 7785.104712957969, 7860.803479912934, 7964.681412743425, 7965.8406831864195, 5413.53984911287, 5530.585206109477, 5542.9168562472405, 5599.524040456444, 5746.224259725051, 9301.72094837156, 9421.340506164777, 9568.66509672186, 9661.3237073407, 9704.563294651616, 8603.35474839402, 9765.121139097515, 9795.755106122118, 9828.705138845846, 9886.950565247127, 7305.3425191732995, 7323.340899319087, 7385.9630701846145, 7396.55620662762, 7404.444666649915, 7708.190639982721, 7882.802929926797, 7890.566018869017, 7941.523508589503, 7948.165442628672, 7118.750843720445, 7197.856042879009, 7219.711211551479, 7224.89162662571, 7365.226651201761, 7071.116110486189, 7079.68141433647, 7084.149902011585, 7191.568505889663, 7215.819604509929, 6548.508861835287, 6667.133272674521, 6672.299408562031, 6717.193936727624, 6729.1252137221845, 3837.731358804857, 4757.928631070467, 4789.4971028041555, 4858.611311095175, 5165.251038599457, 4900.705957642553, 4970.27930950003, 5012.323336349692, 5047.747612619428, 5283.260343551982], "value": 4484.399673262163, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.4778116108421, 318.02525260077874, 328.3007194675342, 329.36925787117656, 332.1728895051323, 4665.580097791198, 5285.119609812652, 5329.587659270296, 5416.968636761205, 5472.783522673324, 4504.68008975378, 5323.589248849058, 5393.085454663587, 5398.811041613544, 5423.918641220375, 3581.6239294874053, 3839.6352346527037, 3875.457435958968, 3942.7831469269177, 4021.477207962975, 3406.5401170841865, 4003.259397011562, 4086.734362266458, 4094.8566800162002, 4110.362893751473, 3429.777064490813, 4065.715399922007, 4072.816723247446, 4096.344584866422, 4103.876883693494, 3090.176795690531, 3837.6546110850018, 3980.960190398088, 4018.859339923103, 4108.5275193352845, 3119.4385010698065, 3295.9011229663147, 3544.1848374873666, 3544.627814079536, 3560.786352818839, 4205.474315710536, 4264.8249392809585, 4567.173130747694, 5009.824656137054, 5155.453363990788, 4402.449693788277, 4492.9550704493195, 4649.395578574764, 4667.603253723438, 4681.361523466793, 4228.703990720651, 4918.434380046305, 5233.555147812441, 5327.30745003151, 5548.975808184549, 3908.0, 3916.6279203475624, 3925.8233379497706, 3932.997085743117, 3954.9123990902444, 4988.401391833003, 5131.255373173139, 5245.583692403106, 5732.767454997195, 6025.8192254232445, 2841.4033053058674, 3243.4810430331036, 3736.5516138063062, 3858.2283543291455, 3937.9564415429936, 3356.3287342531103, 3613.512175856226, 3936.37018077112, 3945.546262179829, 3965.1054730047226, 4104.789087219217, 4446.199684056901, 4601.332806743004, 4628.467726211529, 4659.534046595313, 5435.103207970719, 5559.138333558305, 5569.637973531708, 5581.302337207849, 5588.776448941997, 1221.4182633489652, 1268.4989429175423, 1315.7368925831117, 1328.5416531296687, 1340.383423625124, 656.8505116046917, 715.3669002932443, 782.7827727883083, 816.8529664660299, 828.2193284438385, 4363.781810909441, 5104.719240441737, 5187.481251874782, 5236.973815130977, 5247.9737601311745, 4902.7058376497525, 4958.380202474691, 5812.38969908162, 5830.708464576839, 5831.329397119301, 4460.907077765968, 4847.884986453065, 4869.440014398318, 4985.651004429655, 5316.973415132899, 2601.8829152688177, 2860.685324614291, 3059.2351912021995, 3065.417570661567, 3108.689131086873, 5810.767569297181, 6099.451049405575, 6106.702380714364, 6130.080487926846, 6132.50939924805, 5640.041192997201, 5924.8815023700145, 5939.396362981971, 6009.729562169713, 6013.5567463808375, 5293.761780719877, 5693.889691510199, 5798.362180160263, 5999.850003749942, 6061.515078793598, 5410.567154627699, 5622.413689702619, 5832.766689332379, 5884.440268483224, 5885.676287804212, 4642.837500687595, 5632.464915832989, 5796.59423840344, 5804.2744656917885, 5840.386759390203, 6101.84745381369, 6111.541634377529, 6129.448349648553, 6143.477804386677, 6187.938120618916, 3569.7322700798063, 3685.9262814744093, 3915.1190982028816, 3918.392649139388, 3933.4296527002853, 4982.501749824988, 5866.064198814466, 5867.037146241121, 5873.119032145041, 5884.58807883444, 5464.0, 5471.2887324647545, 5647.5481961442165, 5654.293213348332, 5672.262605861211, 4642.073539481077, 5034.546890779848, 5403.9459605403445, 5651.802186923438, 5674.716264186856, 4692.554207350297, 4931.471243914398, 5318.0, 5574.9442505574425, 5654.7455364509515, 4723.008168284561, 5111.667741596784, 5507.504324610804, 5701.800436984686, 5737.565608597851, 4996.100701873697, 5066.949330506648, 5542.334919809648, 5556.972215138899, 5575.944240557705], "value": 4056.697892719504, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.2450406536549, 683.6445048574744, 698.2598445647614, 737.8524295140974, 738.6048464071721, 14733.631659208526, 15062.77405838912, 15436.073835569874, 15495.92252038741, 15832.366705331802, 11974.940125299361, 14322.57032289033, 14479.493217737381, 14492.420303187886, 14509.347079381427, 733.0982891044019, 748.3040772081962, 900.819836032796, 907.3104440625123, 928.679605536089, 911.5168960450972, 917.0691747875878, 917.1653795046509, 918.4626993208981, 921.262989608314, 964.1178321835512, 965.2037069417723, 966.7438128895848, 967.1585720423207, 967.6226271754022, 752.642494814964, 925.4586067150725, 946.6166202687854, 946.9905300946971, 958.8561715742625, 714.2999860137095, 715.2454160860291, 715.3132992327372, 716.4447553146301, 716.8637958787814, 14701.970862039661, 14748.336324865406, 14750.409983600643, 14763.261836908112, 14768.55694329161, 10140.594376224943, 12067.275963442222, 12163.51345946161, 12222.61588131683, 12443.813342799865, 10754.300970436896, 10785.245032847704, 10839.13286937043, 10878.619248326271, 11242.775144497064, 4016.297147999118, 4089.20260549193, 4147.958520414818, 4156.625903668684, 4159.667226621863, 72492.27507724961, 73320.26679733241, 73444.0, 75019.0, 75305.24694753037, 9276.582553785096, 9375.561994205134, 9480.0, 9683.273754468442, 9804.950975245078, 20875.0, 20944.266950656653, 20977.475563111046, 21005.579888402146, 21012.26457073995, 7460.291272329228, 7545.811354716176, 7574.166841647414, 7622.695092196361, 7719.421043421766, 4068.3694027425795, 4099.262132816062, 4101.794910254535, 4119.217348703737, 4409.779511024436, 3535.116220944764, 3626.546681664792, 3632.3461776880417, 3641.289948460078, 3644.325799727059, 554.7919530176184, 579.6493121661418, 640.1166390381244, 650.853557949467, 651.9706613202417, 405.41620067103275, 546.1643685161708, 568.5366426362501, 609.2201981463734, 612.9478994285536, 6337.6197428154455, 7697.923020769721, 8192.385571082194, 8447.746567602982, 8692.52191129494, 579.3771695427432, 586.6597373523347, 593.5014587746227, 602.4818655955875, 606.4844881850391, 3635.7454978151277, 3727.235916637083, 3783.848646054182, 3838.654521093115, 4087.89780255496, 69115.0, 69424.0, 70046.64976675084, 98756.01243987613, 100228.0, 72086.0, 75927.24072759201, 83168.58415708004, 83803.0, 85078.57460712781, 71606.64196679191, 74559.62720186473, 75343.24656753472, 77092.2290777085, 78439.43121137659, 8056.355491560544, 8223.917760822314, 8334.458260213067, 8368.497890126611, 8460.957695211484, 45617.54382456266, 46340.536594633624, 47335.28997064978, 47554.76222618865, 47608.52391476041, 63893.68053159704, 65490.36274092995, 66391.33608663852, 67235.64675236538, 68438.65780671065, 10950.780984380428, 11275.730432395952, 11334.886651133384, 11550.480228389575, 12105.939470302592, 11533.0, 12015.579454719043, 12062.896266837546, 12202.511899523919, 12401.751964960831, 11280.0, 11344.0, 11457.942710286396, 11568.24806387582, 11918.284902905853, 11317.434128293717, 11455.083593312682, 11659.708507287385, 12398.25610463375, 12469.812952805896, 10965.232433729563, 11090.279131856403, 11172.776544469227, 11482.885171148182, 11571.19001669875, 9290.303227258119, 9312.48781317007, 9417.246620270498, 9427.198688111588, 9430.10414010668, 9478.431294122374, 9514.619415223313, 9548.092931171528, 9556.76108097303, 9882.160016398399], "value": 6877.907768684027, "unit": "score"}], "name": "dromaeo_css", "value": 7014.166932053492, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 107.0, 111.0, 110.0, 112.0, 111.0, 112.0, 110.0, 110.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 138.0, 196.0, 116.0, 139.0, 135.0, 117.0, 171.0, 117.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 186.0, 185.0, 184.0, 184.0, 184.0, 181.0, 183.0, 184.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 57.0, 124.0, 57.0, 61.0, 57.0, 59.0, 109.0, 56.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 102.0, 151.0, 109.0, 114.0, 81.0, 103.0, 80.0, 79.0, 102.0], "value": 101.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 88.0, 85.0, 87.0, 85.0, 88.0, 85.0, 87.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 98.0, 93.0, 129.0, 140.0, 129.0], "value": 105.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 84.0, 86.0, 83.0, 102.0, 132.0, 84.0, 83.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 57.0, 57.0, 57.0, 58.0, 130.0, 94.0, 89.0], "value": 71.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 75.0, 69.0, 71.0, 68.0, 65.0, 67.0, 68.0, 67.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 83.0, 83.0, 86.0, 100.0, 104.0, 107.0, 83.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 158.0, 148.0, 149.0, 144.0, 143.0, 147.0, 227.0, 142.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 169.0, 167.0, 167.0, 170.0, 169.0, 169.0, 175.0, 169.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 71.0, 80.0, 84.0, 86.0, 85.0, 67.0, 67.0, 65.0], "value": 73.8, "unit": "score"}], "name": "kraken", "value": 1517.5, "alertThreshold": 2.0}]} 05:05:45 INFO - Return code: 0 05:05:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:05:45 ERROR - # TBPL SUCCESS # 05:05:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:45 INFO - 2016-05-12 05:05:45.173 screenresolution[1676:9616] starting screenresolution argv=screenresolution get 05:05:45 INFO - 2016-05-12 05:05:45.176 screenresolution[1676:9616] Display 0: 1024x768x32@60 05:05:45 INFO - 2016-05-12 05:05:45.182 screenresolution[1677:9619] starting screenresolution argv=screenresolution list 05:05:45 INFO - Available Modes on Display 0 05:05:45 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 05:05:45 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 05:05:45 INFO - Intel Iris: 05:05:45 INFO - Chipset Model: Intel Iris 05:05:45 INFO - Type: GPU 05:05:45 INFO - Bus: Built-In 05:05:45 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:45 INFO - Vendor: Intel (0x8086) 05:05:45 INFO - Device ID: 0x0a2e 05:05:45 INFO - Revision ID: 0x0009 05:05:45 INFO - Displays: 05:05:45 INFO - Display: 05:05:45 INFO - Resolution: 1024 x 768 @ 60 Hz 05:05:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:45 INFO - Display Serial Number: 1024x768 60 05:05:45 INFO - Main Display: Yes 05:05:45 INFO - Mirror: Off 05:05:45 INFO - Online: Yes 05:05:45 INFO - Rotation: Supported 05:05:45 INFO - Return code: 0 05:05:45 INFO - Running post-action listener: _resource_record_post_action 05:05:45 INFO - ##### 05:05:45 INFO - ##### Finished run-tests step (success) 05:05:45 INFO - ##### 05:05:45 INFO - Running post-run listener: _resource_record_post_run 05:05:46 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 43173888; Write bytes: 691999232; Read time: 279; Write time: 1888 05:05:46 INFO - TinderboxPrint: CPU usage
53.5% 05:05:46 INFO - TinderboxPrint: I/O read bytes / time
43,173,888 / 279 05:05:46 INFO - TinderboxPrint: I/O write bytes / time
691,999,232 / 1,888 05:05:46 INFO - TinderboxPrint: CPU idle
2,256.6 (46.5%) 05:05:46 INFO - TinderboxPrint: CPU system
963.8 (19.9%) 05:05:46 INFO - TinderboxPrint: CPU user
1,633.3 (33.7%) 05:05:46 INFO - TinderboxPrint: Swap in / out
445,452,288 / 0 05:05:46 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 379861504; Write bytes: 374969856; Read time: 30081; Write time: 754 05:05:46 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 37814784; Write bytes: 294276096; Read time: 222; Write time: 1092 05:05:46 INFO - Running post-run listener: _upload_blobber_files 05:05:46 INFO - Blob upload gear active. 05:05:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:05:46 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 05:05:46 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'] 05:05:46 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 05:05:46 INFO - (blobuploader) - INFO - Open directory for files ... 05:05:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:05:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:05:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:48 INFO - (blobuploader) - INFO - Done attempting. 05:05:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:05:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:05:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:49 INFO - (blobuploader) - INFO - Done attempting. 05:05:49 INFO - (blobuploader) - INFO - Iteration through files over. 05:05:49 INFO - Return code: 0 05:05:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:05:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:05:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc9f5da09f4cc97ef5c2eb45f9a41d88b7b66fd1caca31197ecbfdf56cd873f60a8750b23d6dd5eebf1710e72a91ab44bf81c94378e9d1d5fcf9ec8272a9d8a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba9a335edca6674aa34ee97de342ddbdacbc9e8397fd0a4f0ba3aa5e3c90d4985993ab4773bb42d9e7bbc23326bf3c24293981aecac5287cca4b4ee854c50e98"} 05:05:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:05:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:05:49 INFO - Contents: 05:05:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc9f5da09f4cc97ef5c2eb45f9a41d88b7b66fd1caca31197ecbfdf56cd873f60a8750b23d6dd5eebf1710e72a91ab44bf81c94378e9d1d5fcf9ec8272a9d8a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba9a335edca6674aa34ee97de342ddbdacbc9e8397fd0a4f0ba3aa5e3c90d4985993ab4773bb42d9e7bbc23326bf3c24293981aecac5287cca4b4ee854c50e98"} 05:05:49 INFO - Running post-run listener: copy_logs_to_upload_dir 05:05:49 INFO - Copying logs to upload dir... 05:05:49 INFO - mkdir: /builds/slave/test/build/upload/logs 05:05:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.289594 ========= master_lag: 9.74 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-05-12 05:05:58.790893) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-12 05:05:58.796037) ========= 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.jek88u00jG/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.lA3pPYZsOj/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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/7dc9f5da09f4cc97ef5c2eb45f9a41d88b7b66fd1caca31197ecbfdf56cd873f60a8750b23d6dd5eebf1710e72a91ab44bf81c94378e9d1d5fcf9ec8272a9d8a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba9a335edca6674aa34ee97de342ddbdacbc9e8397fd0a4f0ba3aa5e3c90d4985993ab4773bb42d9e7bbc23326bf3c24293981aecac5287cca4b4ee854c50e98"} build_url:https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011227 build_url: 'https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/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/7dc9f5da09f4cc97ef5c2eb45f9a41d88b7b66fd1caca31197ecbfdf56cd873f60a8750b23d6dd5eebf1710e72a91ab44bf81c94378e9d1d5fcf9ec8272a9d8a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba9a335edca6674aa34ee97de342ddbdacbc9e8397fd0a4f0ba3aa5e3c90d4985993ab4773bb42d9e7bbc23326bf3c24293981aecac5287cca4b4ee854c50e98"}' ========= master_lag: 1.30 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-12 05:06:00.110007) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:06:00.110362) ========= 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.jek88u00jG/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.lA3pPYZsOj/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.004912 ========= master_lag: 0.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:06:00.243450) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:06:00.243758) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:06:00.254110) ========= ========= Total master_lag: 11.23 =========