builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0093 starttime: 1461932129.94 results: success (0) buildid: 20160429030215 builduid: 29582e4dd47643738e0a948071b178d8 revision: 8c3fd523d75bd30f691ca2d6cfdad18d576392a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:29.937208) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:29.937555) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:29.937984) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:29.971292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-29 05:15:29.971599) ========= 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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-04-29 05:15:29-- 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% 8.31M=0.001s 2016-04-29 05:15:30 (8.31 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.808271 ========= master_lag: 4.55 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-29 05:15:35.330436) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:35.330719) ========= 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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.019738 ========= master_lag: 0.42 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:35.775065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-29 05:15:35.775413) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c3fd523d75bd30f691ca2d6cfdad18d576392a1 --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 8c3fd523d75bd30f691ca2d6cfdad18d576392a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oMIJ7T6BIN/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.SgW0q7M74k/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-04-29 05:15:35,892 truncating revision to first 12 chars 2016-04-29 05:15:35,892 Setting DEBUG logging. 2016-04-29 05:15:35,892 attempt 1/10 2016-04-29 05:15:35,892 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c3fd523d75b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 05:15:36,950 unpacking tar archive at: mozilla-central-8c3fd523d75b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.308103 ========= master_lag: 3.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-04-29 05:15:40.218970) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:40.219234) ========= script_repo_revision: 8c3fd523d75bd30f691ca2d6cfdad18d576392a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:40.219589) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:40.219910) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 05:15:40.249593) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-04-29 05:15:40.249939) ========= /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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:15:40 INFO - MultiFileLogger online at 20160429 05:15:40 in /builds/slave/test 05:15:40 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 05:15:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:15:40 INFO - {'append_to_log': False, 05:15:40 INFO - 'base_work_dir': '/builds/slave/test', 05:15:40 INFO - 'blob_upload_branch': 'Firefox', 05:15:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:15:40 INFO - 'branch': 'Firefox', 05:15:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:15:40 INFO - 'config_files': ('talos/mac_config.py',), 05:15:40 INFO - 'default_actions': ('clobber', 05:15:40 INFO - 'read-buildbot-config', 05:15:40 INFO - 'download-and-extract', 05:15:40 INFO - 'populate-webroot', 05:15:40 INFO - 'create-virtualenv', 05:15:40 INFO - 'install', 05:15:40 INFO - 'run-tests'), 05:15:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:15:40 INFO - 'download_minidump_stackwalk': True, 05:15:40 INFO - 'download_symbols': 'ondemand', 05:15:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:15:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:15:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:15:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:15:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:15:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:15:40 INFO - 'installer_path': 'installer.exe', 05:15:40 INFO - 'log_level': 'info', 05:15:40 INFO - 'log_name': 'talos', 05:15:40 INFO - 'log_to_console': True, 05:15:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:15:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:15:40 INFO - 'opt_config_files': (), 05:15:40 INFO - 'pip_index': False, 05:15:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:40 INFO - 'cmd': ('bash', 05:15:40 INFO - '-c', 05:15:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:40 INFO - 'enabled': True, 05:15:40 INFO - 'halt_on_failure': False, 05:15:40 INFO - 'name': 'check_screen_resolution'},), 05:15:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:15:40 INFO - 'cmd': ('bash', 05:15:40 INFO - '-c', 05:15:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:15:40 INFO - 'enabled': True, 05:15:40 INFO - 'halt_on_failure': False, 05:15:40 INFO - 'name': 'check_screen_resolution'},), 05:15:40 INFO - 'run_cmd_checks_enabled': True, 05:15:40 INFO - 'sps_profile': False, 05:15:40 INFO - 'sps_profile_interval': 0, 05:15:40 INFO - 'suite': 'dromaeojs', 05:15:40 INFO - 'system_bits': '32', 05:15:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:15:40 INFO - 'title': 't-yosemite-r7-0093', 05:15:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:15:40 INFO - 'use_talos_json': True, 05:15:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:15:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:15:40 INFO - 'work_dir': 'build'} 05:15:40 INFO - ##### 05:15:40 INFO - ##### Running clobber step. 05:15:40 INFO - ##### 05:15:40 INFO - Running pre-action listener: _resource_record_pre_action 05:15:40 INFO - Running main action method: clobber 05:15:40 INFO - rmtree: /builds/slave/test/build 05:15:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:15:41 INFO - Running post-action listener: _resource_record_post_action 05:15:41 INFO - ##### 05:15:41 INFO - ##### Running read-buildbot-config step. 05:15:41 INFO - ##### 05:15:41 INFO - Running pre-action listener: _resource_record_pre_action 05:15:41 INFO - Running main action method: read_buildbot_config 05:15:41 INFO - Using buildbot properties: 05:15:41 INFO - { 05:15:41 INFO - "project": "", 05:15:41 INFO - "product": "firefox", 05:15:41 INFO - "script_repo_revision": "production", 05:15:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:15:41 INFO - "repository": "", 05:15:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:15:41 INFO - "buildid": "20160429030215", 05:15:41 INFO - "stage_platform": "macosx64", 05:15:41 INFO - "basedir": "/builds/slave/test", 05:15:41 INFO - "nightly_build": "True", 05:15:41 INFO - "buildnumber": 233, 05:15:41 INFO - "slavename": "t-yosemite-r7-0093", 05:15:41 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:15:41 INFO - "platform": "yosemite_r7", 05:15:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:15:41 INFO - "branch": "mozilla-central", 05:15:41 INFO - "repo_path": "mozilla-central", 05:15:41 INFO - "revision": "8c3fd523d75bd30f691ca2d6cfdad18d576392a1", 05:15:41 INFO - "pgo_build": "False", 05:15:41 INFO - "builduid": "29582e4dd47643738e0a948071b178d8", 05:15:41 INFO - "slavebuilddir": "test" 05:15:41 INFO - } 05:15:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:15:41 INFO - Running post-action listener: _resource_record_post_action 05:15:41 INFO - ##### 05:15:41 INFO - ##### Running download-and-extract step. 05:15:41 INFO - ##### 05:15:41 INFO - Running pre-action listener: _resource_record_pre_action 05:15:41 INFO - Running main action method: download_and_extract 05:15:41 INFO - mkdir: /builds/slave/test/build/tests 05:15:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:41 INFO - https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:15:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/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 05:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/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 05:15:43 INFO - Downloaded 1199 bytes. 05:15:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:15:43 INFO - Using the following test package requirements: 05:15:43 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:15:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:43 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:15:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:43 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:15:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:43 INFO - u'jsshell-mac.zip'], 05:15:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:43 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:15:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:15:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:43 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:15:43 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:43 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:15:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:43 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:15:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:15:43 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:15:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:43 INFO - https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:15:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/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 05:15:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/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 05:15:45 INFO - Downloaded 18124442 bytes. 05:15: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'] 05:15: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 05:15:48 INFO - Return code: 0 05:15:48 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 05:15:48 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')]} 05:15:48 INFO - https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:15:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:15:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/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 05:15:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/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 05:16:00 INFO - Downloaded 18124442 bytes. 05:16:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:16:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:16:04 INFO - Return code: 0 05:16: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')]} 05:16:04 INFO - https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:16:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:16:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:16:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/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 05:16:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/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 05:16:05 INFO - Downloaded 10840145 bytes. 05:16:05 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'] 05:16:05 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 05:16:06 INFO - Return code: 0 05:16: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')]} 05:16:06 INFO - https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:16:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:16:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:16:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7MKWzaIRcqOBGjrngFAAw/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 05:16:15 INFO - Downloaded 110409928 bytes. 05:16:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:16:15 INFO - mkdir: /builds/slave/test/properties 05:16:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:16:15 INFO - Writing to file /builds/slave/test/properties/build_url 05:16:15 INFO - Contents: 05:16:15 INFO - build_url:https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:16:17 INFO - Running post-action listener: _resource_record_post_action 05:16:17 INFO - Running post-action listener: set_extra_try_arguments 05:16:17 INFO - ##### 05:16:17 INFO - ##### Running populate-webroot step. 05:16:17 INFO - ##### 05:16:17 INFO - Running pre-action listener: _resource_record_pre_action 05:16:17 INFO - Running main action method: populate_webroot 05:16:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:16:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:16:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:16:17 INFO - '--tppagecycles', 05:16:17 INFO - '7'], 05:16:17 INFO - 'tests': ['tsvgm']}}, 05:16:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:16:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:16:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:16:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:16:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:17 INFO - 'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:16:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:17 INFO - 'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['damp', 'tps']}, 05:16:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:17 INFO - 'tests': ['damp', 'tps']}, 05:16:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['dromaeo_dom']}, 05:16:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:16:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['a11yr', 05:16:17 INFO - 'ts_paint', 05:16:17 INFO - 'tpaint', 05:16:17 INFO - 'sessionrestore', 05:16:17 INFO - 'sessionrestore_no_auto_restore', 05:16:17 INFO - 'tabpaint']}, 05:16:17 INFO - 'other-e10s': {'tests': ['a11yr', 05:16:17 INFO - 'ts_paint', 05:16:17 INFO - 'tpaint', 05:16:17 INFO - 'sessionrestore', 05:16:17 INFO - 'sessionrestore_no_auto_restore', 05:16:17 INFO - 'tabpaint']}, 05:16:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:16:17 INFO - 'ts_paint', 05:16:17 INFO - 'tpaint', 05:16:17 INFO - 'sessionrestore', 05:16:17 INFO - 'sessionrestore_no_auto_restore', 05:16:17 INFO - 'tabpaint']}, 05:16:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:16:17 INFO - 'ts_paint', 05:16:17 INFO - 'tpaint', 05:16:17 INFO - 'sessionrestore', 05:16:17 INFO - 'sessionrestore_no_auto_restore', 05:16:17 INFO - 'tabpaint']}, 05:16:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['a11yr', 05:16:17 INFO - 'ts_paint', 05:16:17 INFO - 'tpaint', 05:16:17 INFO - 'sessionrestore', 05:16:17 INFO - 'sessionrestore_no_auto_restore', 05:16:17 INFO - 'tabpaint']}, 05:16:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['a11yr', 05:16:17 INFO - 'ts_paint', 05:16:17 INFO - 'tpaint', 05:16:17 INFO - 'sessionrestore', 05:16:17 INFO - 'sessionrestore_no_auto_restore', 05:16:17 INFO - 'tabpaint']}, 05:16:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['tsvgx', 05:16:17 INFO - 'tsvgr_opacity', 05:16:17 INFO - 'tart', 05:16:17 INFO - 'tscrollx', 05:16:17 INFO - 'cart']}, 05:16:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:16:17 INFO - 'tsvgr_opacity', 05:16:17 INFO - 'tart', 05:16:17 INFO - 'tscrollx', 05:16:17 INFO - 'cart']}, 05:16:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:17 INFO - 'talos_options': ['--disable-e10s'], 05:16:17 INFO - 'tests': ['tp5o']}, 05:16:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:17 INFO - 'tests': ['tp5o']}, 05:16:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:17 INFO - 'talos_options': ['--disable-e10s', 05:16:17 INFO - '--xperf_path', 05:16:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:17 INFO - 'tests': ['tp5n']}, 05:16:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:17 INFO - 'talos_options': ['--xperf_path', 05:16:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:17 INFO - 'tests': ['tp5n']}}, 05:16:17 INFO - 'talos.zip': {'path': '', 05:16:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:16:17 INFO - Running post-action listener: _resource_record_post_action 05:16:17 INFO - ##### 05:16:17 INFO - ##### Running create-virtualenv step. 05:16:17 INFO - ##### 05:16:17 INFO - Running pre-action listener: _resource_record_pre_action 05:16:17 INFO - Running main action method: create_virtualenv 05:16:17 INFO - Creating virtualenv /builds/slave/test/build/venv 05:16:17 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 05:16:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:16:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:16:17 INFO - Using real prefix '/tools/python27' 05:16:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:16:17 INFO - Installing distribute.............................................................................................................................................................................................done. 05:16:20 INFO - Installing pip.................done. 05:16:20 INFO - Return code: 0 05:16:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:20 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 0x1017cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017df420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cda48ce50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, '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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:16:20 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 05:16:20 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 05:16:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:16:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:20 INFO - 'HOME': '/Users/cltbld', 05:16:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:20 INFO - 'LOGNAME': 'cltbld', 05:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:20 INFO - 'MOZ_NO_REMOTE': '1', 05:16:20 INFO - 'NO_EM_RESTART': '1', 05:16:20 INFO - 'PAGER': '/bin/cat', 05:16:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:20 INFO - 'PWD': '/builds/slave/test', 05:16:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:20 INFO - 'SHELL': '/bin/bash', 05:16:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:16:20 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:16:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:20 INFO - 'USER': 'cltbld', 05:16:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:20 INFO - 'XPC_FLAGS': '0x0', 05:16:20 INFO - 'XPC_SERVICE_NAME': '0', 05:16:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:21 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 05:16:21 INFO - Cleaning up... 05:16:21 INFO - Return code: 0 05:16:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:16: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')]} 05:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16: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')]} 05:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1017cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017df420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cda48ce50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, '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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:16:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:16:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:16:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:16:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:21 INFO - 'HOME': '/Users/cltbld', 05:16:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:21 INFO - 'LOGNAME': 'cltbld', 05:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:21 INFO - 'MOZ_NO_REMOTE': '1', 05:16:21 INFO - 'NO_EM_RESTART': '1', 05:16:21 INFO - 'PAGER': '/bin/cat', 05:16:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:21 INFO - 'PWD': '/builds/slave/test', 05:16:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:21 INFO - 'SHELL': '/bin/bash', 05:16:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:16:21 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:16:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:21 INFO - 'USER': 'cltbld', 05:16:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:21 INFO - 'XPC_FLAGS': '0x0', 05:16:21 INFO - 'XPC_SERVICE_NAME': '0', 05:16:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:21 INFO - Downloading/unpacking psutil>=0.7.1 05:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:16:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:24 INFO - Installing collected packages: psutil 05:16:24 INFO - Running setup.py install for psutil 05:16:24 INFO - building 'psutil._psutil_osx' extension 05:16:24 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 05:16:24 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 05:16:24 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 05:16:24 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 05:16:24 INFO - building 'psutil._psutil_posix' extension 05:16:24 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 05:16:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:16:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:16:24 INFO - ^ 05:16:24 INFO - 1 warning generated. 05:16:24 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 05:16:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:16:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:16:25 INFO - Successfully installed psutil 05:16:25 INFO - Cleaning up... 05:16:25 INFO - Return code: 0 05:16:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1017cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017df420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cda48ce50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, '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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:25 INFO - 'HOME': '/Users/cltbld', 05:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:25 INFO - 'LOGNAME': 'cltbld', 05:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:25 INFO - 'MOZ_NO_REMOTE': '1', 05:16:25 INFO - 'NO_EM_RESTART': '1', 05:16:25 INFO - 'PAGER': '/bin/cat', 05:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:25 INFO - 'PWD': '/builds/slave/test', 05:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:25 INFO - 'SHELL': '/bin/bash', 05:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:16:25 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:25 INFO - 'USER': 'cltbld', 05:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:25 INFO - 'XPC_FLAGS': '0x0', 05:16:25 INFO - 'XPC_SERVICE_NAME': '0', 05:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:25 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:27 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:16:27 INFO - Installing collected packages: mozsystemmonitor 05:16:27 INFO - Running setup.py install for mozsystemmonitor 05:16:28 INFO - Successfully installed mozsystemmonitor 05:16:28 INFO - Cleaning up... 05:16:28 INFO - Return code: 0 05:16:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:16:28 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')]} 05:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:28 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')]} 05:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:28 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 0x1017cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017df420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cda48ce50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, '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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:16:28 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 05:16:28 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 05:16:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:16:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:28 INFO - 'HOME': '/Users/cltbld', 05:16:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:28 INFO - 'LOGNAME': 'cltbld', 05:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:28 INFO - 'MOZ_NO_REMOTE': '1', 05:16:28 INFO - 'NO_EM_RESTART': '1', 05:16:28 INFO - 'PAGER': '/bin/cat', 05:16:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:28 INFO - 'PWD': '/builds/slave/test', 05:16:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:28 INFO - 'SHELL': '/bin/bash', 05:16:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:16:28 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:16:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:28 INFO - 'USER': 'cltbld', 05:16:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:28 INFO - 'XPC_FLAGS': '0x0', 05:16:28 INFO - 'XPC_SERVICE_NAME': '0', 05:16:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:28 INFO - Downloading/unpacking blobuploader==1.2.4 05:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:30 INFO - Downloading blobuploader-1.2.4.tar.gz 05:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:16:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16: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 05:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:16:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:16:31 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 05:16:31 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 05:16:31 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 05:16:31 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 05:16:31 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 05:16:31 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 05:16:31 INFO - Downloading docopt-0.6.1.tar.gz 05:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:16:32 INFO - Installing collected packages: blobuploader, requests, docopt 05:16:32 INFO - Running setup.py install for blobuploader 05:16:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:16:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:16:32 INFO - Running setup.py install for requests 05:16:32 INFO - Running setup.py install for docopt 05:16:32 INFO - Successfully installed blobuploader requests docopt 05:16:32 INFO - Cleaning up... 05:16:32 INFO - Return code: 0 05:16:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:32 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')]} 05:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:32 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')]} 05:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017df420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cda48ce50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, '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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:32 INFO - 'HOME': '/Users/cltbld', 05:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:32 INFO - 'LOGNAME': 'cltbld', 05:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:32 INFO - 'MOZ_NO_REMOTE': '1', 05:16:32 INFO - 'NO_EM_RESTART': '1', 05:16:32 INFO - 'PAGER': '/bin/cat', 05:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:32 INFO - 'PWD': '/builds/slave/test', 05:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:32 INFO - 'SHELL': '/bin/bash', 05:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:16:32 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:32 INFO - 'USER': 'cltbld', 05:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:32 INFO - 'XPC_FLAGS': '0x0', 05:16:32 INFO - 'XPC_SERVICE_NAME': '0', 05:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:33 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-oot_cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:33 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-VRzrM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:33 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-8drdLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:33 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-qusZmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:33 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-eEefOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:33 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Sxl7rL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:33 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-P8FRjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:33 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-fXe5RV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:33 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-YaebzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:34 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-qoxP3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:34 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-BtyoMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:34 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-fklBpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:34 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-_sYZrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:34 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-biXTLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:34 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-bjwCOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:34 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-G9Dma7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:34 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-5_AhvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:16:34 INFO - Running setup.py install for manifestparser 05:16:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:16:35 INFO - Running setup.py install for mozcrash 05:16:35 INFO - Running setup.py install for mozdebug 05:16:35 INFO - Running setup.py install for mozdevice 05:16:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:16:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:16:35 INFO - Running setup.py install for mozfile 05:16:35 INFO - Running setup.py install for mozhttpd 05:16:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:16:35 INFO - Running setup.py install for mozinfo 05:16:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:16:35 INFO - Running setup.py install for mozInstall 05:16:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:16:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:16:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:16:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:16:36 INFO - Running setup.py install for mozleak 05:16:36 INFO - Running setup.py install for mozlog 05:16:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:16:36 INFO - Running setup.py install for moznetwork 05:16:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:16:36 INFO - Running setup.py install for mozprocess 05:16:36 INFO - Running setup.py install for mozprofile 05:16:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:16:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:16:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:16:36 INFO - Running setup.py install for mozrunner 05:16:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:16:37 INFO - Running setup.py install for mozscreenshot 05:16:37 INFO - Running setup.py install for moztest 05:16:37 INFO - Running setup.py install for mozversion 05:16:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:16:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:16:37 INFO - Cleaning up... 05:16:37 INFO - Return code: 0 05:16:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:37 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 0x1017cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017df420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cda48ce50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, '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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:16:37 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 05:16:37 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 05:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:37 INFO - 'HOME': '/Users/cltbld', 05:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:37 INFO - 'LOGNAME': 'cltbld', 05:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:37 INFO - 'MOZ_NO_REMOTE': '1', 05:16:37 INFO - 'NO_EM_RESTART': '1', 05:16:37 INFO - 'PAGER': '/bin/cat', 05:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:37 INFO - 'PWD': '/builds/slave/test', 05:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:37 INFO - 'SHELL': '/bin/bash', 05:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:16:37 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:37 INFO - 'USER': 'cltbld', 05:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:37 INFO - 'XPC_FLAGS': '0x0', 05:16:37 INFO - 'XPC_SERVICE_NAME': '0', 05:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:16:37 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-GHm2X1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:16:37 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)) 05:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:16:37 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-wDJ0tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:16:38 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)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-mv6ID4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:16:38 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)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-RxTi6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:16:38 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)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-yurf9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:16:38 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)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Bdn27L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:16:38 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)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-8tIDMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:16:38 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)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-DlCA_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:16:38 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)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-gBZM1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:16:38 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)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-T8rbDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:16:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-UGqCll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:16:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:16:38 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-FyctzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:16:39 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)) 05:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:16:39 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-oHqCAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:16:39 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)) 05:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:16:39 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-13Fa9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:16:39 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)) 05:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:39 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-I0Yryn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:16:39 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)) 05:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:16:39 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-8K2H2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:16:39 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)) 05:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:16:39 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-5TlZ7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:16:39 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)) 05:16:39 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)) 05:16:39 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)) 05:16:39 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)) 05:16:39 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)) 05:16:39 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)) 05:16:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:16:39 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 05:16:39 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 05:16:39 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 05:16:39 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 05:16:39 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 05:16:39 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 05:16:42 INFO - Downloading blessings-1.6.tar.gz 05:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:16:42 INFO - Installing collected packages: blessings 05:16:42 INFO - Running setup.py install for blessings 05:16:42 INFO - Successfully installed blessings 05:16:42 INFO - Cleaning up... 05:16:42 INFO - Return code: 0 05:16:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:16:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:42 INFO - Reading from file tmpfile_stdout 05:16:42 INFO - Current package versions: 05:16:42 INFO - blessings == 1.6 05:16:42 INFO - blobuploader == 1.2.4 05:16:42 INFO - docopt == 0.6.1 05:16:42 INFO - manifestparser == 1.1 05:16:42 INFO - mozInstall == 1.12 05:16:42 INFO - mozcrash == 0.17 05:16:42 INFO - mozdebug == 0.1 05:16:42 INFO - mozdevice == 0.48 05:16:42 INFO - mozfile == 1.2 05:16:42 INFO - mozhttpd == 0.7 05:16:42 INFO - mozinfo == 0.9 05:16:42 INFO - mozleak == 0.1 05:16:42 INFO - mozlog == 3.1 05:16:42 INFO - moznetwork == 0.27 05:16:42 INFO - mozprocess == 0.22 05:16:42 INFO - mozprofile == 0.28 05:16:42 INFO - mozrunner == 6.11 05:16:42 INFO - mozscreenshot == 0.1 05:16:42 INFO - mozsystemmonitor == 0.0 05:16:42 INFO - moztest == 0.7 05:16:42 INFO - mozversion == 1.4 05:16:42 INFO - psutil == 3.1.1 05:16:42 INFO - requests == 1.2.3 05:16:42 INFO - wsgiref == 0.1.2 05:16:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:43 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 0x1017cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017df420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cda48ce50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, '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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:16:43 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 05:16:43 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 05:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:43 INFO - 'HOME': '/Users/cltbld', 05:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:43 INFO - 'LOGNAME': 'cltbld', 05:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:43 INFO - 'MOZ_NO_REMOTE': '1', 05:16:43 INFO - 'NO_EM_RESTART': '1', 05:16:43 INFO - 'PAGER': '/bin/cat', 05:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:43 INFO - 'PWD': '/builds/slave/test', 05:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:43 INFO - 'SHELL': '/bin/bash', 05:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:16:43 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:43 INFO - 'USER': 'cltbld', 05:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:43 INFO - 'XPC_FLAGS': '0x0', 05:16:43 INFO - 'XPC_SERVICE_NAME': '0', 05:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:43 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)) 05:16:43 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)) 05:16:43 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)) 05:16:43 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)) 05:16:43 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)) 05:16:43 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)) 05:16:43 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)) 05:16:43 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)) 05:16:43 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)) 05:16:43 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)) 05:16:43 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)) 05:16:43 INFO - Cleaning up... 05:16:43 INFO - Return code: 0 05:16:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:43 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 0x1017cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017df420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cda48ce50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, '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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:16:43 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 05:16:43 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 05:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:43 INFO - 'HOME': '/Users/cltbld', 05:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:43 INFO - 'LOGNAME': 'cltbld', 05:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:43 INFO - 'MOZ_NO_REMOTE': '1', 05:16:43 INFO - 'NO_EM_RESTART': '1', 05:16:43 INFO - 'PAGER': '/bin/cat', 05:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:43 INFO - 'PWD': '/builds/slave/test', 05:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:43 INFO - 'SHELL': '/bin/bash', 05:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:16:43 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:43 INFO - 'USER': 'cltbld', 05:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:43 INFO - 'XPC_FLAGS': '0x0', 05:16:43 INFO - 'XPC_SERVICE_NAME': '0', 05:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:43 INFO - Downloading/unpacking jsonschema 05:16:43 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 05:16:43 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 05:16:43 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 05:16:43 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 05:16:43 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 05:16:43 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 05:16:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:16:46 INFO - Downloading/unpacking functools32 (from jsonschema) 05:16:46 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 05:16:46 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 05:16:46 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 05:16:46 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 05:16:46 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 05:16:46 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 05:16:46 INFO - Downloading functools32-3.2.3-2.tar.gz 05:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:16:46 INFO - warning: no files found matching '*.txt' 05:16:46 INFO - no previously-included directories found matching 'build' 05:16:46 INFO - no previously-included directories found matching 'dist' 05:16:46 INFO - no previously-included directories found matching '.git*' 05:16:46 INFO - Installing collected packages: jsonschema, functools32 05:16:46 INFO - Running setup.py install for functools32 05:16:47 INFO - warning: no files found matching '*.txt' 05:16:47 INFO - no previously-included directories found matching 'build' 05:16:47 INFO - no previously-included directories found matching 'dist' 05:16:47 INFO - no previously-included directories found matching '.git*' 05:16:47 INFO - Successfully installed jsonschema functools32 05:16:47 INFO - Cleaning up... 05:16:47 INFO - Return code: 0 05:16:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:16:47 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')]} 05:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:47 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')]} 05:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:16:47 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 0x1017cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017df420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8cda48ce50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, '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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 05:16:47 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 05:16:47 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 05:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:47 INFO - 'HOME': '/Users/cltbld', 05:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:47 INFO - 'LOGNAME': 'cltbld', 05:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:47 INFO - 'MOZ_NO_REMOTE': '1', 05:16:47 INFO - 'NO_EM_RESTART': '1', 05:16:47 INFO - 'PAGER': '/bin/cat', 05:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:47 INFO - 'PWD': '/builds/slave/test', 05:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:47 INFO - 'SHELL': '/bin/bash', 05:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:16:47 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:47 INFO - 'USER': 'cltbld', 05:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:47 INFO - 'XPC_FLAGS': '0x0', 05:16:47 INFO - 'XPC_SERVICE_NAME': '0', 05:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:47 INFO - Downloading/unpacking flake8 05:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:16:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:16:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:16:50 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 05:16:50 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 05:16:50 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 05:16:50 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 05:16:50 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 05:16:50 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 05:16:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:16:50 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 05:16:50 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 05:16:50 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 05:16:50 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 05:16:50 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 05:16:50 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 05:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:16:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:16:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:16:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:16:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:16:51 INFO - no previously-included directories found matching 'docs/_build' 05:16:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:16:51 INFO - Running setup.py install for pep8 05:16:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:16:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:16:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:16:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:16:51 INFO - no previously-included directories found matching 'docs/_build' 05:16:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:16:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:16:51 INFO - Cleaning up... 05:16:51 INFO - Return code: 0 05:16:51 INFO - Running post-action listener: _resource_record_post_action 05:16:51 INFO - Running post-action listener: _start_resource_monitoring 05:16:51 INFO - Starting resource monitoring. 05:16:51 INFO - ##### 05:16:51 INFO - ##### Running install step. 05:16:51 INFO - ##### 05:16:51 INFO - Running pre-action listener: _resource_record_pre_action 05:16:51 INFO - Running main action method: install 05:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:16:51 INFO - Reading from file tmpfile_stdout 05:16:51 INFO - Detecting whether we're running mozinstall >=1.0... 05:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:16:51 INFO - Reading from file tmpfile_stdout 05:16:51 INFO - Output received: 05:16:51 INFO - Usage: mozinstall [options] installer 05:16:51 INFO - Options: 05:16:51 INFO - -h, --help show this help message and exit 05:16:51 INFO - -d DEST, --destination=DEST 05:16:51 INFO - Directory to install application into. [default: 05:16:51 INFO - "/builds/slave/test"] 05:16:51 INFO - --app=APP Application being installed. [default: firefox] 05:16:51 INFO - mkdir: /builds/slave/test/build/application 05:16:51 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'] 05:16:51 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 05:17:25 INFO - Reading from file tmpfile_stdout 05:17:25 INFO - Output received: 05:17:25 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:17:25 INFO - Running post-action listener: _resource_record_post_action 05:17:25 INFO - ##### 05:17:25 INFO - ##### Running run-tests step. 05:17:25 INFO - ##### 05:17:25 INFO - Running pre-action listener: _resource_record_pre_action 05:17:25 INFO - Running main action method: run_tests 05:17:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:17:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:17:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:17:25 INFO - 2016-04-29 05:17:25.355 screenresolution[1918:15992] starting screenresolution argv=screenresolution get 05:17:25 INFO - 2016-04-29 05:17:25.358 screenresolution[1918:15992] Display 0: 1600x1200x32@60 05:17:25 INFO - 2016-04-29 05:17:25.363 screenresolution[1919:15994] starting screenresolution argv=screenresolution list 05:17:25 INFO - Available Modes on Display 0 05:17:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:17:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:17:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:17:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:17:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:17:25 INFO - Intel Iris: 05:17:25 INFO - Chipset Model: Intel Iris 05:17:25 INFO - Type: GPU 05:17:25 INFO - Bus: Built-In 05:17:25 INFO - VRAM (Dynamic, Max): 1536 MB 05:17:25 INFO - Vendor: Intel (0x8086) 05:17:25 INFO - Device ID: 0x0a2e 05:17:25 INFO - Revision ID: 0x0009 05:17:25 INFO - Displays: 05:17:25 INFO - Display: 05:17:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:17:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:17:25 INFO - Display Serial Number: 1600x1200 60 05:17:25 INFO - Main Display: Yes 05:17:25 INFO - Mirror: Off 05:17:25 INFO - Online: Yes 05:17:25 INFO - Rotation: Supported 05:17:25 INFO - Return code: 0 05:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:17:25 INFO - Python 2.7.3 05:17:25 INFO - Return code: 0 05:17:25 INFO - grabbing minidump binary from tooltool 05:17:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:25 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10166ee00>, '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 0x101497db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101669b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:17:25 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 05:17:25 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 05:17:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:17:25 INFO - Return code: 0 05:17:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:17:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:17:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:17:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:17:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:17:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:17:26 INFO - Return code: 0 05:17:26 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 05:17:26 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0093 --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 05:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oMIJ7T6BIN/Render', 05:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:26 INFO - 'HOME': '/Users/cltbld', 05:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:26 INFO - 'LOGNAME': 'cltbld', 05:17:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:17:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:26 INFO - 'MOZ_NO_REMOTE': '1', 05:17:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:17:26 INFO - 'NO_EM_RESTART': '1', 05:17:26 INFO - 'PAGER': '/bin/cat', 05:17:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:26 INFO - 'PWD': '/builds/slave/test', 05:17:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:26 INFO - 'SHELL': '/bin/bash', 05:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SgW0q7M74k/Listeners', 05:17:26 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 05:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:26 INFO - 'USER': 'cltbld', 05:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:26 INFO - 'XPC_FLAGS': '0x0', 05:17:26 INFO - 'XPC_SERVICE_NAME': '0', 05:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:26 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 05:17:27 INFO - mozversion application_buildid: 20160429030215 05:17:27 INFO - mozversion application_changeset: 8c3fd523d75bd30f691ca2d6cfdad18d576392a1 05:17:27 INFO - mozversion application_display_name: Nightly 05:17:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:17:27 INFO - mozversion application_name: Firefox 05:17:27 INFO - mozversion application_remotingname: firefox 05:17:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:17:27 INFO - mozversion application_vendor: Mozilla 05:17:27 INFO - mozversion application_version: 49.0a1 05:17:27 INFO - mozversion platform_buildid: 20160429030215 05:17:27 INFO - mozversion platform_changeset: 8c3fd523d75bd30f691ca2d6cfdad18d576392a1 05:17:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:17:27 INFO - mozversion platform_version: 49.0a1 05:17:27 INFO - using testdate: 1461932247 05:17:27 INFO - actual date: 1461932247 05:17:27 INFO - starting webserver on 'localhost:52453' 05:17:27 INFO - SUITE-START | Running 2 tests 05:17:27 INFO - TEST-START | dromaeo_css 05:17:27 INFO - operating with platform_type : mac_ 05:17:27 INFO - Initialising browser for dromaeo_css test... 05:17:27 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpWEhcUF/profile http://localhost:52453/getInfo.html) 05:17:29 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 05:17:29 INFO - PROCESS | 1937 | colorDepth:24 05:17:29 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 05:17:29 INFO - PROCESS | 1937 | __metrics 05:17:29 INFO - PROCESS | 1937 | 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:17:29 ERROR - PROCESS | 1937 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:17:30 INFO - TEST-INFO | 1937: exit 0 05:17:30 INFO - Browser initialized. 05:17:30 INFO - Running cycle 1/1 for dromaeo_css test... 05:17:30 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpWEhcUF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:17:42 INFO - PROCESS | 1940 | RSS: Main: 239362048 05:17:42 INFO - PROCESS | 1940 | 05:18:33 INFO - PROCESS | 1940 | 1461932313372 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] 05:20:44 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52453/tests/dromaeo/cssquery-ext.html) 05:20:45 INFO - PROCESS | 1940 | RSS: Main: 382255104 05:20:45 INFO - PROCESS | 1940 | 05:23:48 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-ext.html (next: http://localhost:52453/tests/dromaeo/cssquery-jquery.html) 05:23:49 INFO - PROCESS | 1940 | RSS: Main: 373272576 05:23:49 INFO - PROCESS | 1940 | 05:26:51 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52453/tests/dromaeo/cssquery-mootools.html) 05:26:52 INFO - PROCESS | 1940 | RSS: Main: 368525312 05:26:52 INFO - PROCESS | 1940 | 05:29:54 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52453/tests/dromaeo/cssquery-prototype.html) 05:29:55 INFO - PROCESS | 1940 | RSS: Main: 367067136 05:29:55 INFO - PROCESS | 1940 | 05:32:58 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52453/tests/dromaeo/cssquery-yui.html) 05:32:58 INFO - PROCESS | 1940 | RSS: Main: 340570112 05:32:58 INFO - PROCESS | 1940 | 05:36:01 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-yui.html (next: http://localhost:52453/tests/dromaeo/cssquery-dojo.html) 05:36:02 INFO - PROCESS | 1940 | RSS: Main: 375541760 05:36:02 INFO - PROCESS | 1940 | 05:36:02 INFO - PROCESS | 1940 | __start_tp_report 05:36:02 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 05:36:02 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 05:36:02 INFO - PROCESS | 1940 | |i|pagename|runs| 05:36:02 INFO - PROCESS | 1940 | |0;dojo.html;3337.1490269981155;3381.898543043708;3412.744044196684;3415.060858263979;3445.4142795724724;5463.617546771728;5569.637973531718;5583.3300003999475;5596.86007849804;5597.356304025038;5583.832485025446;5614.887702245953;5670.914936275949;5700.800471983482;5745.109508026252;4151.190517849021;5014.498550144993;5077.365329333833;5097.184450487923;5224.190250511177;5173.172292433212;5176.50823171798;5180.533751962323;5207.036698210824;5263.736813159346;4997.125503036972;5081.110805609003;5084.3898732152375;5115.923261151086;5178.119719647669;4213.536510983789;5120;5147.0992576298995;5149.562287205592;5156.92264616031;5072.746362681888;5129.307543481619;5134;5142.997115562467;5164.767585458626;5345.705986170759;5410.702411367373;5478.671279723229;5552.333719953591;5552.750126244288;6095.207623009025;6117.113018612321;6121.724522396458;6129.448349648553;6133.172021777048;6146.846328841814;6198.597091189104;6207.317195108534;6218.036204388327;6219.875602487925;5549.667019978814;5637;5657.20799088128;5693.37372888986;5750.9648263312165;6127.571070025145;6311.905321420182;6316.62100273985;6316.968415157895;6322.841928951813;5906.26171728534;5919.408059194132;5930.673812940329;5939.584229103922;5963.165156878041;5956.225690660186;6078.270607527036;6104.145419641254;6142.539309551801;6149.600275982047;6032.758689652364;6102.580142287592;6109.75560977556;6110.528382738159;6142.57002009855;6079.939200608026;6086;6090.390960903874;6103.511719062464;6103.511719062464;4407.096545208225;4748.786304616231;4818.301346304766;4822.373091498153;4825.758712064453;4106.1992911382595;4381;4415.492218394861;4438.467383913951;4438.622717069021;5542.75057622408;5551.250581171571;5551.500364967173;5556.194351818964;5560.4161563036105;6014.8195554133445;6082.5438092142385;6095.146679464879;6103.084537319349;6119.5410344224365;5609.579281553818;5610.803621873215;5615.606907516435;5617.494425501724;5618.129189975561;5283.762230699628;5287.576993840484;5288.550473209743;5294.285271488377;5297.443768404339;6256.812295631047;6348.650824204621;6357.618542887441;6360.268569114519;6362.522810789117;6148.569600127948;6155.876882462236;6170.259568851767;6171.9074213887725;6180.783672571439;6324.620522768649;6334.493240540838;6344.524160687874;6372.203474565679;6387.680615969276;5703.62926409782;5972.940270597413;6028.125921741411;6033.487153591994;6061.393860613904;5457.972710136424;5731.083026715705;6109.266887973472;6111;6165.784197553064;5681.77272909079;5771.826845194651;5917.615355001911;5940.55445841555;5965.075413310944;4844.32179494864;5889.617174883619;5913.881722365614;5930.28836539618;5977.252843394575;5760.539156867357;5777.104548794943;5782.508486778503;5791.0734282514595;5807.593468457168;5784.074548072456;5895.233619629421;5910.586258961831;5918.201042859331;5933.732982015906;5778.56660750437;5813.593048486566;5821.738021788945;5875.588708790344;5880.323762767336 05:36:02 INFO - PROCESS | 1940 | |1;ext.html;488.12869028783615;494.18212857720476;502.6230327254557;557.1809440123022;643.5237923936286;31033.862211691325;39009.60990390095;40084.59915400849;40112.59887401125;40272.39591406124;45097;45895;45915.77042114793;45920.540794592045;45946;8540.658373665046;9637.277204209695;9822.968588298198;9823.999999999964;9848.113669769718;8691.695790647329;11306.321620702744;11535.884641153565;11553.884461155407;11556.653300401002;11625.360605166712;11768.705782355466;11769.058475321954;11774.47014884328;11774.882251177463;8411.285040771541;8654.913450865473;8657.480551166951;11543;11712.472938717778;8651.480911145289;11256.662300131009;11532.481038353295;11545.191836571443;11558.30650160993;38749.806250968846;39513.01217469558;39526.40710389346;39572.40641390382;39839.40240896389;3259.4621887388553;3349.815760133192;3485.7559970802054;3531.04661741331;3566.0193446802077;21933.451663708376;27116.32209194786;27970.860145699346;29135;29472.821087156488;2700.986495067512;2736.5621500559814;2744.986275068632;2784.290006048465;2858.342581206334;440248;441468.7926560379;444122;444997.7750111261;445837.7708111471;34474.65525344764;63708.36291637117;65802.34197658057;65828.6708566454;65840;35680.28639427249;35817.8209108958;35893.64106358955;35922;35933.46099808505;14737.631559210891;14990.700185996437;15002.474913377979;15013.69972600542;15026.248687565578;11327.830082548768;11550;12296.077794165474;12479.5008199671;12578.874211257771;8183.263506284463;8220.301274391624;8239.95880020596;8317.8336433271;8325.333973282122;3128.0772172209254;3208.5508028875975;3339.348826978716;3376.561047063915;3378.915527111793;791.6041979010495;904.6110172625703;1026.5893642543042;1052.3054783842626;1063.250408462048;29301.974430895243;30086.398272034436;30110;30599.54100688447;30865.382692346026;996.9152622027209;1029.264076185531;1071.255477443185;1123.393367581513;1223.565634199864;14702.852971470362;14908.925455372872;14911.776323355158;15230.923845380923;15233.93362464639;642608;659404;660243;661747;666780;61699.38300617026;62394.688026559576;62492.37507624865;62650.37349626537;62876.68561657162;1368.5210176438168;1498.7976623155953;1531.961700957485;1534.6700459401059;1543.1744016950877;25986;29732.256693582833;29868.726328104378;30473;30974;53137.202941956675;53741.65645858885;54126.917461651334;54414.18378724243;54460.18778685454;496516.51741742506;501511;502980;504571;506797;21305;22719.09123635036;25964.480710386066;26451.33871653147;27377.86311068432;43911.78044109759;43935.34096988484;44380.778096109316;44843.87890302768;45239.54760452485;36108.27783444369;37948.86153415402;38031.188306379154;38261.043473912265;38830;37130.88607341784;37248.81375593105;37346;38065.809670951465;38185.42721859119;37043;37415.43876841904;37605.435918460695;38133;38160.04599885025;31148.377032459677;33355.666443335256;33460.83269583636;33656.83171584126;33906.83046584751;23927.521449570562;24345.78271086403;24382.75617243805;24870.253892383258;24998.625020624342 05:36:02 INFO - PROCESS | 1940 | |2;jquery.html;347.3019231344996;395.88915103770955;396.5915107439338;404.24406360106605;410.3885211035558;20016.89991550044;21204.363869083914;21733.565328693418;21782;22150.22474213403;21596.1361545538;22032.559348813014;22337.664935025987;22396;22482.77517224827;5769.538436925046;6097.878042439149;6118.357572454895;6119.908201376983;6122.571420000601;4172.77049762263;4195.46865394132;5689.943100568983;5771.740271687763;6571.145751052357;5749.626274292157;6352;6503.414692677658;6510.609363438185;6519.771807986722;5776;6165.845853853644;6172.166757487771;6173.104899789528;6175.351588083254;3872.9419058714143;3882.9223415531533;5386.13821788512;5989.281286245635;6021;13566.186028838301;13865.37605807741;13868.93065534666;14149.787753183607;25061.37346566332;2027.726256955307;2049.405672355015;2111.4193596761015;2183.858049226795;2198.9890050549643;9789.314747967645;10014.79970400588;10346.948265258701;10367.377957322586;15380.077195368202;1692.7545505901576;1712.871534634895;1757.3673477548073;1799.3342463288536;1828.1133650179652;105845;117136;118514;122727.3863630676;129506.35246823894;20211.898940505496;20495.795042049685;20659.380218593466;20697.58604827895;20914.026334996302;34420.13949651229;35133.297334053175;35300.76447324331;35302;35304.94085177449;10214.897851021395;10958.493282149635;11011.28426652281;11035.006849383593;11111.444427778579;7400.333969942731;8981.550922453851;9025.142611451902;9101.635934562675;9118.544072796334;8495.516838096351;9578.90421095794;9894.455804930798;10030.699079027641;10047.296689231684;3154.542591324245;3350.530925670408;3723.3670276053135;3791.3933770596573;3863.7635956494214;910.1853840812436;957.8228027814737;965.584798536622;988.4464699768009;1038.1383451735041;20471.283505077252;20712.58574828495;21090.89454552748;21202.363929082152;21288.467788305108;853.6073406233203;930.1907340613709;939.2298315381322;981.563204374061;1008.909198172168;8735.868961965576;8770.868436973451;11257.04315133223;11390.943045284721;11467;462085;570081;571871;578659;598678;78914;79285;80953.19046809574;121773;133895;1003.0771690045176;1005.3013155856755;1028.3367228137809;1050;1050.8108540462638;18608.37136758319;18849.869007859023;18943.526411839815;18956.715649264996;19231.711524326867;52246.865188088836;53814;54360;54448.18327725008;55375;77474.2252577467;80608;81272;82198.58900705459;82684.58657706913;7298.2701729826595;8058.355331573577;8293.502389856629;8301.335893128415;8312.584370781316;9534.332596718163;12238.938805305916;12691.238525688488;12769.170003949714;13025.609231723063;10321.812991506073;10489.423081730272;10522;10942.835857462303;11024.50389732448;10595.258331917;10695.411752353695;10833.02502774754;11118.387179013087;11222.49498772541;10962.835557466486;11099.667009989713;11168.832467513157;11440.199186056896;11498.48256828428;8127;8215.260626543639;8273;8444.451110677786;8647.913520864711;10432.634857779942;10798.838017429902;11079.778404432027;11101;11129.276597021168 05:36:02 INFO - PROCESS | 1940 | |3;mootools.html;1377.8553251908543;1579.1946107485187;1608.9758653620195;1845.6124213915077;1979.4457551885469;7713.92286077139;8123.0252369715645;8450.323974082074;8711.651533938635;8714.085021072775;8436.198561136698;8480.491170529756;8582.098879617644;8591.699290524833;8596.742097737062;2153.160267495678;2425.7452967438385;2457.3242358351417;2468.814838887082;2469.333280014395;2209.5805093422678;2326.8836558172106;2508.7240403555684;2516.2954372775653;2521.9117330893423;2415.577273977056;2445.4131008557997;2449.6570480132796;2454.006127518358;2455.3002394317623;2138.101997161196;2362.598358279097;2433.4403087289934;2445.193086281523;2447.3269850791085;2452.5953217719057;2480.85114893107;2481.702195736523;2485.608059486693;2486.539990101819;8541.743747687578;8648.264897483721;8649.524276164808;8662.436941598777;8683.913160868373;5068.949310506885;5312.76092575835;5343.438938911436;5343.599230057723;5346.625736198468;3352.513885486591;3422.6235114137426;3486.093615659947;3560.359135355635;3588.982055089734;978.8140253351911;1019.2406657040478;1044.3055368180228;1051.8369652703843;1066.807974564578;11454.198206125575;11515.078793696484;11520.884791152148;11532.077433805276;11541.019013383873;4781.521847815191;4802.207635740134;4910.607151427877;4913.31213630092;4946.307516947631;9789.265805064648;9827.459489728133;9842.50787460624;9855.507224638739;9876.851847222297;5403.054465468527;5411.350637923396;5413.512783849473;5417.729113544307;5418.187271909166;3816.9427458588143;4388.4733831940375;4799.736014519165;4850;4977.054359671651;3984.362501999724;4000.959990400117;4015.1166743316426;4073.5111786585207;4095.631393174569;1342.2148043394723;1347.4071408580194;1359.2184493916038;1361.8297712785902;1376.5595009596832;767.1714548287836;906.1844340093968;937.4141161773891;944.9480278584743;973.2651847854966;5947.940520594825;5986.2517185351835;6042;6094.634321940698;6259.71831267594;787.6534324897027;912.9224015958586;919.8850143732034;972.5654659377503;978.8874279457954;3923.6468717815533;3953.940690889639;4000.4999375078114;4055.797210139481;4061.268971585084;10251.28241023136;10547.789044219073;10810.135189184846;10831.83752243717;10871.239013268996;10508.42203678805;10790.974857388535;10793.67618971432;10810.35137891729;10817.350958942488;6646.833829154213;8031.196880312039;8093.919060809317;8163.224493673091;8268;5820.854478638068;5909.379515151019;5927.733252003584;5929.495992840484;5932.525397968066;9603.615855365986;9708.6116555337;10027.298089133983;10065.094141527297;10126.898731012596;9131.863022054542;10016.849747253942;10068.546915388679;10160.136388407069;10305.587776489156;6032;6643.634600097041;7029.332213439715;7040.929590704028;7068.045813815069;8207.179282071746;8219.383546233938;8301.626426810688;8316.376271779762;8317.625706843328;7168;7216.9639151806;7419.777406677808;7477.14012888503;7514.361279291322;7151.213366529573;7274.16347120088;7311.159216690149;7446.776596702108;7456.478046536908;6961.825954351182;7008.053912721717;7047.260037696173;7048.154221493453;7059.435245180476;4125.525564560114;4712;4905;5006.199008158823;5074.543291103819;4822.614190864652;4896.510348965075;4954.157793175241;5061.620378471703;5171.9482805172975 05:36:02 INFO - PROCESS | 1940 | |4;prototype.html;306.404044134159;318.13308733700666;323.8672144420788;326.65864171940336;327.37798183451434;4753.932039562788;5126.307948426961;5206.687598744078;5239.476052394763;5280.363087442895;4271.63691086258;4884.99572762751;5145.202493613496;5154.819581314655;5200.765965531551;3351.698347148756;3696.815159242034;3734.7198960077967;3996.260691772017;4028.7784171870535;3368.214846131554;4012.8796136115816;4044.373122166069;4057.9391309130388;4115.176964607075;3453.6719011693976;3580.7493475456727;3699.907502312437;3958.9208215835665;3959.3467077932105;3291.374638818617;3333.93332133356;3921.960780392202;3967.6230758077936;3980.661643760284;3047.360054388581;3431.7769344992494;3442.8794992175153;3449.6032956209856;3457.4468085106514;4760.047990401933;4797.376341075638;5024.221245706921;5062.417821950486;5063.949360506384;4472.508024117345;4522.68341216115;4524.547545245482;4541.864533866534;4565;5174.301469301634;5458.972705136489;5506.669599824023;5515.503604675599;5545.667259964415;3401.8943843250795;3538.575370955476;3595.586507551639;3825.1393436476847;3839.1937693084487;5394.73026348681;5626.383731564842;5630.887382252373;5714.685622306851;5734.419511219968;3765.2657731742047;3848.5958974307855;3849.2493963676816;3850.4994350734223;3863.903402414906;3265.853036613358;3265.918352041218;3897.472787127654;3916.706247031484;3945.5659877414037;3733.7386382953478;4280.777451509154;4344.500382456058;4459.929616891923;4510.864674059718;5207;5486.725663716798;5489.39616642169;5515.889682206333;5537.335519737658;1195.8564972203392;1280.103927250928;1293.4438191577713;1404.8524904885044;1431.7329163690208;796.74902405742;905.4612505559181;951.219999600335;951.3816019587185;962.6390103711109;5446.945530544644;5490.478404551562;5495.450454954553;5508.669479831223;5514.145307477347;5727.739897222605;5756.7121643917635;5902.6458412495385;5915.231019967377;6176.938230617637;4835.347228124228;5314.335708036496;5394.705270734996;5518.613697041249;5556.555475561945;2582.87085645717;2945.4109178164454;3205.7275131613605;3234.450143475615;3243.626982896997;6233.594816336924;6330.802944512991;6364.840878978062;6392.776252831221;6420.935790642127;6256.773426692178;6275.529335299871;6318.557700960889;6324.841878953063;6356.904646430307;5573.749181286852;5848.502877255396;6104.938950610437;6322.4942004639515;6422.74309027643;4774.498677638937;5993.700314984146;6105.419985101408;6117.113018612187;6123.663198524124;4545.386402818326;4980.925286120783;5458.727063646722;5473.75368108444;5484.945150548443;5073.3911930568565;5588.552915766811;5660.462256085665;5677.347105082803;5887.587868849139;3441.8451169696923;3716.312482190803;3828.138668799609;3836.731428799957;3843.231353729321;5116.718580478109;5180.663256888291;5181.145111056633;5686.943130568641;5704.543636508986;4626.37543931565;5483.588730845123;5522.723863806874;5533.889322213614;5566.471185237396;5130.81991142032;5459.918101228405;5490.505854472957;5493.626593351662;5520.282363292747;5425.755840987244;5484.451554844483;5522.5581953442825;5533.3083364579425;5535.086710692691;4343.826246950087;5081.593472522115;5281;5452.536534394462;5604.0298641719955;4634.698744581591;5137.409197942135;5267.473252674702;5348.465153484621;5531.557475401878 05:36:02 INFO - PROCESS | 1940 | |5;yui.html;667.8230268978722;712.4264966701803;730.9451791115664;741.4465652264538;755.4636208292112;14582.125072495657;14948.476803311887;15070.547883563497;15078;15082.472113476031;14929.104253744783;15456.304466299016;15471.613209669764;15472.767908481383;15571.98782079164;742.3467348733108;767.612355760343;939.1547607153582;953.5947222430474;961.2406199102701;926.865604487349;960.5581432541039;965.3918031640056;966.4732720667218;967.5017366056486;986.856905748666;1011.8988101189896;1012.2964539644913;1012.4178597306542;1012.4431562640555;783.8471498057881;959.1415682963708;988.1255089246033;1000.8398656215007;1001.114014097529;742.2354974376401;750.6434443639282;751.0274194917595;751.4101430377127;751.6880494594736;12455.626331210078;15022.699546009128;15082.396704131821;15093.773593396108;15118.924405378013;12230.205036672676;12252.142350035501;12408.131430799847;12462.376881155906;12581.245125292511;9860.852087218698;10035.548400322003;10075;10438.321509101885;10867.728306792314;3467.358538670354;3481.791092534456;3544.326577950181;3549.562427216981;3567.3935430976794;84141.57929210376;84691.57654211689;85429;85600.57199713962;86177;9253.074692530694;9279.768005799773;9358;9452.385594936308;9918.851217231748;20516.487087822923;20777.896110519654;20815.959202039838;20826.166953322;20842.895785520974;6122.5408094392205;7249.782506524813;7352.19125896151;7503.699852005859;7544.245575442522;3415.8633654653536;4043.1913617276664;4082.9591704082577;4098.467199264136;4314.870553883388;3302.612902580922;3304.273059926812;3474.6004209516227;3825.7895815729844;3841.2125514269505;682.576802382526;686.1663079358603;692.1866806502339;698.6541661877403;711.2283172757512;501.4709481497056;594.70859278953;597.2981746447906;616.7101462312706;621.1242148570584;8040.959795201104;8058.435909486397;8195.549244791475;8325.583720814057;8336.249737523653;585.8213244960328;604.4167378479788;607.5686262753494;612.9264488261348;643.5237923936254;3870.322693528621;3871.457995880579;3883.3204189266767;4007.877794217624;4010.258102251093;85750;87357;88783;88815.66776498358;91844.54077729568;53272.73363633157;68574;71460.64269678722;74355;78277.60861195657;65355.36611584843;69706.95439568309;69764;72856.271437286;75735;8076.353891688768;8082.393820463371;8211.71259005932;8219.712310069119;8355.707550235713;42224.78887605542;42661.36007959945;43277;43966.340504893094;44003.33994990142;59226.70386648039;63227;65950.34049659634;67140.97154170765;67386.66306668632;10753.623623173151;10841.67474975752;10845.945770271099;10974.231803773659;11304.943475282571;9676.70969870905;11929.28424294545;11960.222585531912;12056.578019769266;12601.495940162291;11280.661580152608;11305.547778088785;11622.883771162518;11735;11765.999890009103;9290.88509378879;11069.446527673745;11473.196876218584;11479.196456247982;11511.481983310938;11178.720531986766;11198.720031998939;11226;11343.829842552534;11519.654410367702;9509.61961521559;9731;9746.9025309746;9892.432571893636;10128.189744820544;9600.135987761136;9619.422834629944;9680.225581953286;9718.319717619699;9899.554520046759 05:36:02 INFO - PROCESS | 1940 | __end_tp_report 05:36:02 INFO - PROCESS | 1940 | __start_cc_report 05:36:02 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,1289 05:36:02 INFO - PROCESS | 1940 | __end_cc_report 05:36:02 INFO - PROCESS | 1940 | __startTimestamp1461933362103__endTimestamp 05:36:02 INFO - PROCESS | 1940 | 05:36:02 INFO - PROCESS | 1940 | ------- Summary: start ------- 05:36:02 INFO - PROCESS | 1940 | Number of tests: 6 05:36:02 INFO - PROCESS | 1940 | 05:36:02 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5614.25 Median:5777.10 stddev:642.57 (11.1%) stddev-sans-first:619.21 05:36:02 INFO - PROCESS | 1940 | Values: 3337.1 3381.9 3412.7 3415.1 3445.4 5463.6 5569.6 5583.3 5596.9 5597.4 5583.8 5614.9 5670.9 5700.8 5745.1 4151.2 5014.5 5077.4 5097.2 5224.2 5173.2 5176.5 5180.5 5207.0 5263.7 4997.1 5081.1 5084.4 5115.9 5178.1 4213.5 5120.0 5147.1 5149.6 5156.9 5072.7 5129.3 5134.0 5143.0 5164.8 5345.7 5410.7 5478.7 5552.3 5552.8 6095.2 6117.1 6121.7 6129.4 6133.2 6146.8 6198.6 6207.3 6218.0 6219.9 5549.7 5637.0 5657.2 5693.4 5751.0 6127.6 6311.9 6316.6 6317.0 6322.8 5906.3 5919.4 5930.7 5939.6 5963.2 5956.2 6078.3 6104.1 6142.5 6149.6 6032.8 6102.6 6109.8 6110.5 6142.6 6079.9 6086.0 6090.4 6103.5 6103.5 4407.1 4748.8 4818.3 4822.4 4825.8 4106.2 4381.0 4415.5 4438.5 4438.6 5542.8 5551.3 5551.5 5556.2 5560.4 6014.8 6082.5 6095.1 6103.1 6119.5 5609.6 5610.8 5615.6 5617.5 5618.1 5283.8 5287.6 5288.6 5294.3 5297.4 6256.8 6348.7 6357.6 6360.3 6362.5 6148.6 6155.9 6170.3 6171.9 6180.8 6324.6 6334.5 6344.5 6372.2 6387.7 5703.6 5972.9 6028.1 6033.5 6061.4 5458.0 5731.1 6109.3 6111.0 6165.8 5681.8 5771.8 5917.6 5940.6 5965.1 4844.3 5889.6 5913.9 5930.3 5977.3 5760.5 5777.1 5782.5 5791.1 5807.6 5784.1 5895.2 5910.6 5918.2 5933.7 5778.6 5813.6 5821.7 5875.6 5880.3 05:36:02 INFO - PROCESS | 1940 | 05:36:02 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:66168.12 Median:35855.73 stddev:145113.14 (404.7%) stddev-sans-first:145436.43 05:36:02 INFO - PROCESS | 1940 | Values: 488.1 494.2 502.6 557.2 643.5 31033.9 39009.6 40084.6 40112.6 40272.4 45097.0 45895.0 45915.8 45920.5 45946.0 8540.7 9637.3 9823.0 9824.0 9848.1 8691.7 11306.3 11535.9 11553.9 11556.7 11625.4 11768.7 11769.1 11774.5 11774.9 8411.3 8654.9 8657.5 11543.0 11712.5 8651.5 11256.7 11532.5 11545.2 11558.3 38749.8 39513.0 39526.4 39572.4 39839.4 3259.5 3349.8 3485.8 3531.0 3566.0 21933.5 27116.3 27970.9 29135.0 29472.8 2701.0 2736.6 2745.0 2784.3 2858.3 440248.0 441468.8 444122.0 444997.8 445837.8 34474.7 63708.4 65802.3 65828.7 65840.0 35680.3 35817.8 35893.6 35922.0 35933.5 14737.6 14990.7 15002.5 15013.7 15026.2 11327.8 11550.0 12296.1 12479.5 12578.9 8183.3 8220.3 8240.0 8317.8 8325.3 3128.1 3208.6 3339.3 3376.6 3378.9 791.6 904.6 1026.6 1052.3 1063.3 29302.0 30086.4 30110.0 30599.5 30865.4 996.9 1029.3 1071.3 1123.4 1223.6 14702.9 14908.9 14911.8 15230.9 15233.9 642608.0 659404.0 660243.0 661747.0 666780.0 61699.4 62394.7 62492.4 62650.4 62876.7 1368.5 1498.8 1532.0 1534.7 1543.2 25986.0 29732.3 29868.7 30473.0 30974.0 53137.2 53741.7 54126.9 54414.2 54460.2 496516.5 501511.0 502980.0 504571.0 506797.0 21305.0 22719.1 25964.5 26451.3 27377.9 43911.8 43935.3 44380.8 44843.9 45239.5 36108.3 37948.9 38031.2 38261.0 38830.0 37130.9 37248.8 37346.0 38065.8 38185.4 37043.0 37415.4 37605.4 38133.0 38160.0 31148.4 33355.7 33460.8 33656.8 33906.8 23927.5 24345.8 24382.8 24870.3 24998.6 05:36:02 INFO - PROCESS | 1940 | 05:36:02 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:34069.26 Median:23772.07 stddev:92923.10 (390.9%) stddev-sans-first:93149.28 05:36:02 INFO - PROCESS | 1940 | Values: 347.3 395.9 396.6 404.2 410.4 20016.9 21204.4 21733.6 21782.0 22150.2 21596.1 22032.6 22337.7 22396.0 22482.8 5769.5 6097.9 6118.4 6119.9 6122.6 4172.8 4195.5 5689.9 5771.7 6571.1 5749.6 6352.0 6503.4 6510.6 6519.8 5776.0 6165.8 6172.2 6173.1 6175.4 3872.9 3882.9 5386.1 5989.3 6021.0 13566.2 13865.4 13868.9 14149.8 25061.4 2027.7 2049.4 2111.4 2183.9 2199.0 9789.3 10014.8 10346.9 10367.4 15380.1 1692.8 1712.9 1757.4 1799.3 1828.1 105845.0 117136.0 118514.0 122727.4 129506.4 20211.9 20495.8 20659.4 20697.6 20914.0 34420.1 35133.3 35300.8 35302.0 35304.9 10214.9 10958.5 11011.3 11035.0 11111.4 7400.3 8981.6 9025.1 9101.6 9118.5 8495.5 9578.9 9894.5 10030.7 10047.3 3154.5 3350.5 3723.4 3791.4 3863.8 910.2 957.8 965.6 988.4 1038.1 20471.3 20712.6 21090.9 21202.4 21288.5 853.6 930.2 939.2 981.6 1008.9 8735.9 8770.9 11257.0 11390.9 11467.0 462085.0 570081.0 571871.0 578659.0 598678.0 78914.0 79285.0 80953.2 121773.0 133895.0 1003.1 1005.3 1028.3 1050.0 1050.8 18608.4 18849.9 18943.5 18956.7 19231.7 52246.9 53814.0 54360.0 54448.2 55375.0 77474.2 80608.0 81272.0 82198.6 82684.6 7298.3 8058.4 8293.5 8301.3 8312.6 9534.3 12238.9 12691.2 12769.2 13025.6 10321.8 10489.4 10522.0 10942.8 11024.5 10595.3 10695.4 10833.0 11118.4 11222.5 10962.8 11099.7 11168.8 11440.2 11498.5 8127.0 8215.3 8273.0 8444.5 8647.9 10432.6 10798.8 11079.8 11101.0 11129.3 05:36:02 INFO - PROCESS | 1940 | 05:36:02 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:5668.23 Median:4965.61 stddev:3136.06 (63.2%) stddev-sans-first:3128.28 05:36:02 INFO - PROCESS | 1940 | Values: 1377.9 1579.2 1609.0 1845.6 1979.4 7713.9 8123.0 8450.3 8711.7 8714.1 8436.2 8480.5 8582.1 8591.7 8596.7 2153.2 2425.7 2457.3 2468.8 2469.3 2209.6 2326.9 2508.7 2516.3 2521.9 2415.6 2445.4 2449.7 2454.0 2455.3 2138.1 2362.6 2433.4 2445.2 2447.3 2452.6 2480.9 2481.7 2485.6 2486.5 8541.7 8648.3 8649.5 8662.4 8683.9 5068.9 5312.8 5343.4 5343.6 5346.6 3352.5 3422.6 3486.1 3560.4 3589.0 978.8 1019.2 1044.3 1051.8 1066.8 11454.2 11515.1 11520.9 11532.1 11541.0 4781.5 4802.2 4910.6 4913.3 4946.3 9789.3 9827.5 9842.5 9855.5 9876.9 5403.1 5411.4 5413.5 5417.7 5418.2 3816.9 4388.5 4799.7 4850.0 4977.1 3984.4 4001.0 4015.1 4073.5 4095.6 1342.2 1347.4 1359.2 1361.8 1376.6 767.2 906.2 937.4 944.9 973.3 5947.9 5986.3 6042.0 6094.6 6259.7 787.7 912.9 919.9 972.6 978.9 3923.6 3953.9 4000.5 4055.8 4061.3 10251.3 10547.8 10810.1 10831.8 10871.2 10508.4 10791.0 10793.7 10810.4 10817.4 6646.8 8031.2 8093.9 8163.2 8268.0 5820.9 5909.4 5927.7 5929.5 5932.5 9603.6 9708.6 10027.3 10065.1 10126.9 9131.9 10016.8 10068.5 10160.1 10305.6 6032.0 6643.6 7029.3 7040.9 7068.0 8207.2 8219.4 8301.6 8316.4 8317.6 7168.0 7217.0 7419.8 7477.1 7514.4 7151.2 7274.2 7311.2 7446.8 7456.5 6961.8 7008.1 7047.3 7048.2 7059.4 4125.5 4712.0 4905.0 5006.2 5074.5 4822.6 4896.5 4954.2 5061.6 5171.9 05:36:02 INFO - PROCESS | 1940 | 05:36:02 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:4533.92 Median:5141.31 stddev:1443.54 (28.1%) stddev-sans-first:1412.29 05:36:02 INFO - PROCESS | 1940 | Values: 306.4 318.1 323.9 326.7 327.4 4753.9 5126.3 5206.7 5239.5 5280.4 4271.6 4885.0 5145.2 5154.8 5200.8 3351.7 3696.8 3734.7 3996.3 4028.8 3368.2 4012.9 4044.4 4057.9 4115.2 3453.7 3580.7 3699.9 3958.9 3959.3 3291.4 3333.9 3922.0 3967.6 3980.7 3047.4 3431.8 3442.9 3449.6 3457.4 4760.0 4797.4 5024.2 5062.4 5063.9 4472.5 4522.7 4524.5 4541.9 4565.0 5174.3 5459.0 5506.7 5515.5 5545.7 3401.9 3538.6 3595.6 3825.1 3839.2 5394.7 5626.4 5630.9 5714.7 5734.4 3765.3 3848.6 3849.2 3850.5 3863.9 3265.9 3265.9 3897.5 3916.7 3945.6 3733.7 4280.8 4344.5 4459.9 4510.9 5207.0 5486.7 5489.4 5515.9 5537.3 1195.9 1280.1 1293.4 1404.9 1431.7 796.7 905.5 951.2 951.4 962.6 5446.9 5490.5 5495.5 5508.7 5514.1 5727.7 5756.7 5902.6 5915.2 6176.9 4835.3 5314.3 5394.7 5518.6 5556.6 2582.9 2945.4 3205.7 3234.5 3243.6 6233.6 6330.8 6364.8 6392.8 6420.9 6256.8 6275.5 6318.6 6324.8 6356.9 5573.7 5848.5 6104.9 6322.5 6422.7 4774.5 5993.7 6105.4 6117.1 6123.7 4545.4 4980.9 5458.7 5473.8 5484.9 5073.4 5588.6 5660.5 5677.3 5887.6 3441.8 3716.3 3828.1 3836.7 3843.2 5116.7 5180.7 5181.1 5686.9 5704.5 4626.4 5483.6 5522.7 5533.9 5566.5 5130.8 5459.9 5490.5 5493.6 5520.3 5425.8 5484.5 5522.6 5533.3 5535.1 4343.8 5081.6 5281.0 5452.5 5604.0 4634.7 5137.4 5267.5 5348.5 5531.6 05:36:02 INFO - PROCESS | 1940 | 05:36:02 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:17797.43 Median:605.99 stddev:24870.26 (4104.1%) stddev-sans-first:24906.77 05:36:02 INFO - PROCESS | 1940 | Values: 667.8 712.4 730.9 741.4 755.5 14582.1 14948.5 15070.5 15078.0 15082.5 14929.1 15456.3 15471.6 15472.8 15572.0 742.3 767.6 939.2 953.6 961.2 926.9 960.6 965.4 966.5 967.5 986.9 1011.9 1012.3 1012.4 1012.4 783.8 959.1 988.1 1000.8 1001.1 742.2 750.6 751.0 751.4 751.7 12455.6 15022.7 15082.4 15093.8 15118.9 12230.2 12252.1 12408.1 12462.4 12581.2 9860.9 10035.5 10075.0 10438.3 10867.7 3467.4 3481.8 3544.3 3549.6 3567.4 84141.6 84691.6 85429.0 85600.6 86177.0 9253.1 9279.8 9358.0 9452.4 9918.9 20516.5 20777.9 20816.0 20826.2 20842.9 6122.5 7249.8 7352.2 7503.7 7544.2 3415.9 4043.2 4083.0 4098.5 4314.9 3302.6 3304.3 3474.6 3825.8 3841.2 682.6 686.2 692.2 698.7 711.2 501.5 594.7 597.3 616.7 621.1 8041.0 8058.4 8195.5 8325.6 8336.2 585.8 604.4 607.6 612.9 643.5 3870.3 3871.5 3883.3 4007.9 4010.3 85750.0 87357.0 88783.0 88815.7 91844.5 53272.7 68574.0 71460.6 74355.0 78277.6 65355.4 69707.0 69764.0 72856.3 75735.0 8076.4 8082.4 8211.7 8219.7 8355.7 42224.8 42661.4 43277.0 43966.3 44003.3 59226.7 63227.0 65950.3 67141.0 67386.7 10753.6 10841.7 10845.9 10974.2 11304.9 9676.7 11929.3 11960.2 12056.6 12601.5 11280.7 11305.5 11622.9 11735.0 11766.0 9290.9 11069.4 11473.2 11479.2 11511.5 11178.7 11198.7 11226.0 11343.8 11519.7 9509.6 9731.0 9746.9 9892.4 10128.2 9600.1 9619.4 9680.2 9718.3 9899.6 05:36:02 INFO - PROCESS | 1940 | -------- Summary: end -------- 05:36:02 INFO - PROCESS | 1940 | 05:36:02 INFO - TEST-INFO | 1940: exit 0 05:36:02 INFO - TEST-OK | dromaeo_css | took 1115702ms 05:36:02 INFO - TEST-START | kraken 05:36:02 INFO - operating with platform_type : mac_ 05:36:02 INFO - Initialising browser for kraken test... 05:36:02 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmp1M2egA/profile http://localhost:52453/getInfo.html) 05:36:05 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 05:36:05 INFO - PROCESS | 1944 | colorDepth:24 05:36:05 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 05:36:05 INFO - PROCESS | 1944 | __metrics 05:36:05 INFO - PROCESS | 1944 | 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:36:05 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:36:05 INFO - TEST-INFO | 1944: exit 0 05:36:05 INFO - Browser initialized. 05:36:05 INFO - Running cycle 1/1 for kraken test... 05:36:05 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmp1M2egA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:17 INFO - PROCESS | 1947 | RSS: Main: 239312896 05:36:17 INFO - PROCESS | 1947 | 05:36:20 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52453/tests/kraken/driver.html?testName=audio-beat-detection) 05:36:21 INFO - PROCESS | 1947 | RSS: Main: 287465472 05:36:21 INFO - PROCESS | 1947 | 05:36:23 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52453/tests/kraken/driver.html?testName=audio-dft) 05:36:24 INFO - PROCESS | 1947 | RSS: Main: 295596032 05:36:24 INFO - PROCESS | 1947 | 05:36:28 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52453/tests/kraken/driver.html?testName=audio-fft) 05:36:28 INFO - PROCESS | 1947 | RSS: Main: 381882368 05:36:28 INFO - PROCESS | 1947 | 05:36:30 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52453/tests/kraken/driver.html?testName=audio-oscillator) 05:36:31 INFO - PROCESS | 1947 | RSS: Main: 280825856 05:36:31 INFO - PROCESS | 1947 | 05:36:33 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52453/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:36:34 INFO - PROCESS | 1947 | RSS: Main: 285097984 05:36:34 INFO - PROCESS | 1947 | 05:36:36 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52453/tests/kraken/driver.html?testName=imaging-darkroom) 05:36:37 INFO - PROCESS | 1947 | RSS: Main: 325070848 05:36:37 INFO - PROCESS | 1947 | 05:36:40 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52453/tests/kraken/driver.html?testName=imaging-desaturate) 05:36:40 INFO - PROCESS | 1947 | RSS: Main: 325451776 05:36:40 INFO - PROCESS | 1947 | 05:36:43 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52453/tests/kraken/driver.html?testName=json-parse-financial) 05:36:44 INFO - PROCESS | 1947 | RSS: Main: 320573440 05:36:44 INFO - PROCESS | 1947 | 05:36:45 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52453/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:36:46 INFO - PROCESS | 1947 | RSS: Main: 313286656 05:36:46 INFO - PROCESS | 1947 | 05:36:48 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:36:49 INFO - PROCESS | 1947 | RSS: Main: 320962560 05:36:49 INFO - PROCESS | 1947 | 05:36:51 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:36:51 INFO - PROCESS | 1947 | RSS: Main: 321818624 05:36:51 INFO - PROCESS | 1947 | 05:36:54 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:36:55 INFO - PROCESS | 1947 | RSS: Main: 319488000 05:36:55 INFO - PROCESS | 1947 | 05:36:58 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:36:58 INFO - PROCESS | 1947 | RSS: Main: 320307200 05:36:58 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52453/tests/kraken/driver.html?testName=ai-astar) 05:37:00 INFO - PROCESS | 1947 | RSS: Main: 384937984 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | __start_tp_report 05:37:00 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 05:37:00 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 05:37:00 INFO - PROCESS | 1947 | |i|pagename|runs| 05:37:00 INFO - PROCESS | 1947 | |0;ai-astar;112;112;108;112;112;111;113;112;105;114 05:37:00 INFO - PROCESS | 1947 | |1;audio-beat-detection;158;123;143;168;118;144;139;119;171;116 05:37:00 INFO - PROCESS | 1947 | |2;audio-dft;187;186;184;188;187;186;182;184;187;185 05:37:00 INFO - PROCESS | 1947 | |3;audio-fft;76;75;90;92;57;56;61;82;86;55 05:37:00 INFO - PROCESS | 1947 | |4;audio-oscillator;95;128;178;93;79;82;115;78;82;119 05:37:00 INFO - PROCESS | 1947 | |5;imaging-gaussian-blur;88;85;87;85;87;85;89;85;88;85 05:37:00 INFO - PROCESS | 1947 | |6;imaging-darkroom;93;94;95;94;92;94;95;94;94;97 05:37:00 INFO - PROCESS | 1947 | |7;imaging-desaturate;84;86;84;86;84;85;86;87;85;85 05:37:00 INFO - PROCESS | 1947 | |8;json-parse-financial;56;61;60;58;57;58;59;78;60;58 05:37:00 INFO - PROCESS | 1947 | |9;json-stringify-tinderbox;63;73;97;110;108;67;68;67;68;68 05:37:00 INFO - PROCESS | 1947 | |10;stanford-crypto-aes;83;82;83;83;82;82;81;81;83;98 05:37:00 INFO - PROCESS | 1947 | |11;stanford-crypto-ccm;138;148;149;155;135;135;153;157;172;140 05:37:00 INFO - PROCESS | 1947 | |12;stanford-crypto-pbkdf2;171;170;167;168;169;169;170;169;168;164 05:37:00 INFO - PROCESS | 1947 | |13;stanford-crypto-sha256-iterative;68;67;69;70;68;67;68;69;68;66 05:37:00 INFO - PROCESS | 1947 | __end_tp_report 05:37:00 INFO - PROCESS | 1947 | __start_cc_report 05:37:00 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,1974 05:37:00 INFO - PROCESS | 1947 | __end_cc_report 05:37:00 INFO - PROCESS | 1947 | __startTimestamp1461933420870__endTimestamp 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | ------- Summary: start ------- 05:37:00 INFO - PROCESS | 1947 | Number of tests: 14 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:2.64 (2.4%) stddev-sans-first:2.78 05:37:00 INFO - PROCESS | 1947 | Values: 112.0 112.0 108.0 112.0 112.0 111.0 113.0 112.0 105.0 114.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:143.50 stddev:20.78 (14.5%) stddev-sans-first:20.98 05:37:00 INFO - PROCESS | 1947 | Values: 158.0 123.0 143.0 168.0 118.0 144.0 139.0 119.0 171.0 116.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#2] audio-dft Cycles:10 Average:185.60 Median:186.50 stddev:1.84 (1.0%) stddev-sans-first:1.88 05:37:00 INFO - PROCESS | 1947 | Values: 187.0 186.0 184.0 188.0 187.0 186.0 182.0 184.0 187.0 185.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#3] audio-fft Cycles:10 Average:73.00 Median:79.00 stddev:14.63 (18.5%) stddev-sans-first:15.48 05:37:00 INFO - PROCESS | 1947 | Values: 76.0 75.0 90.0 92.0 57.0 56.0 61.0 82.0 86.0 55.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#4] audio-oscillator Cycles:10 Average:104.90 Median:80.50 stddev:31.38 (39.0%) stddev-sans-first:33.08 05:37:00 INFO - PROCESS | 1947 | Values: 95.0 128.0 178.0 93.0 79.0 82.0 115.0 78.0 82.0 119.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.00 stddev:1.58 (1.8%) stddev-sans-first:1.56 05:37:00 INFO - PROCESS | 1947 | Values: 88.0 85.0 87.0 85.0 87.0 85.0 89.0 85.0 88.0 85.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:1.32 (1.4%) stddev-sans-first:1.32 05:37:00 INFO - PROCESS | 1947 | Values: 93.0 94.0 95.0 94.0 92.0 94.0 95.0 94.0 94.0 97.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.03 (1.2%) stddev-sans-first:1.00 05:37:00 INFO - PROCESS | 1947 | Values: 84.0 86.0 84.0 86.0 84.0 85.0 86.0 87.0 85.0 85.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#8] json-parse-financial Cycles:10 Average:60.50 Median:59.50 stddev:6.33 (10.6%) stddev-sans-first:6.50 05:37:00 INFO - PROCESS | 1947 | Values: 56.0 61.0 60.0 58.0 57.0 58.0 59.0 78.0 60.0 58.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#9] json-stringify-tinderbox Cycles:10 Average:78.90 Median:68.00 stddev:18.47 (27.2%) stddev-sans-first:18.67 05:37:00 INFO - PROCESS | 1947 | Values: 63.0 73.0 97.0 110.0 108.0 67.0 68.0 67.0 68.0 68.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:83.00 stddev:5.05 (6.1%) stddev-sans-first:5.35 05:37:00 INFO - PROCESS | 1947 | Values: 83.0 82.0 83.0 83.0 82.0 82.0 81.0 81.0 83.0 98.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:151.00 stddev:11.71 (7.8%) stddev-sans-first:11.82 05:37:00 INFO - PROCESS | 1947 | Values: 138.0 148.0 149.0 155.0 135.0 135.0 153.0 157.0 172.0 140.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:169.00 stddev:1.96 (1.2%) stddev-sans-first:1.86 05:37:00 INFO - PROCESS | 1947 | Values: 171.0 170.0 167.0 168.0 169.0 169.0 170.0 169.0 168.0 164.0 05:37:00 INFO - PROCESS | 1947 | 05:37:00 INFO - PROCESS | 1947 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 05:37:00 INFO - PROCESS | 1947 | Values: 68.0 67.0 69.0 70.0 68.0 67.0 68.0 69.0 68.0 66.0 05:37:00 INFO - PROCESS | 1947 | -------- Summary: end -------- 05:37:00 INFO - PROCESS | 1947 | 05:37:01 INFO - TEST-INFO | 1947: exit 0 05:37:01 INFO - TEST-OK | kraken | took 58945ms 05:37:01 INFO - SUITE-END | took 1174s 05:37:02 INFO - Completed test suite (00:19:35) 05:37:02 INFO - javascript benchmark 05:37:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.1490269981155, 3381.898543043708, 3412.744044196684, 3415.060858263979, 3445.4142795724724, 5463.617546771728, 5569.637973531718, 5583.3300003999475, 5596.86007849804, 5597.356304025038, 5583.832485025446, 5614.887702245953, 5670.914936275949, 5700.800471983482, 5745.109508026252, 4151.190517849021, 5014.498550144993, 5077.365329333833, 5097.184450487923, 5224.190250511177, 5173.172292433212, 5176.50823171798, 5180.533751962323, 5207.036698210824, 5263.736813159346, 4997.125503036972, 5081.110805609003, 5084.3898732152375, 5115.923261151086, 5178.119719647669, 4213.536510983789, 5120.0, 5147.0992576298995, 5149.562287205592, 5156.92264616031, 5072.746362681888, 5129.307543481619, 5134.0, 5142.997115562467, 5164.767585458626, 5345.705986170759, 5410.702411367373, 5478.671279723229, 5552.333719953591, 5552.750126244288, 6095.207623009025, 6117.113018612321, 6121.724522396458, 6129.448349648553, 6133.172021777048, 6146.846328841814, 6198.597091189104, 6207.317195108534, 6218.036204388327, 6219.875602487925, 5549.667019978814, 5637.0, 5657.20799088128, 5693.37372888986, 5750.9648263312165, 6127.571070025145, 6311.905321420182, 6316.62100273985, 6316.968415157895, 6322.841928951813, 5906.26171728534, 5919.408059194132, 5930.673812940329, 5939.584229103922, 5963.165156878041, 5956.225690660186, 6078.270607527036, 6104.145419641254, 6142.539309551801, 6149.600275982047, 6032.758689652364, 6102.580142287592, 6109.75560977556, 6110.528382738159, 6142.57002009855, 6079.939200608026, 6086.0, 6090.390960903874, 6103.511719062464, 6103.511719062464, 4407.096545208225, 4748.786304616231, 4818.301346304766, 4822.373091498153, 4825.758712064453, 4106.1992911382595, 4381.0, 4415.492218394861, 4438.467383913951, 4438.622717069021, 5542.75057622408, 5551.250581171571, 5551.500364967173, 5556.194351818964, 5560.4161563036105, 6014.8195554133445, 6082.5438092142385, 6095.146679464879, 6103.084537319349, 6119.5410344224365, 5609.579281553818, 5610.803621873215, 5615.606907516435, 5617.494425501724, 5618.129189975561, 5283.762230699628, 5287.576993840484, 5288.550473209743, 5294.285271488377, 5297.443768404339, 6256.812295631047, 6348.650824204621, 6357.618542887441, 6360.268569114519, 6362.522810789117, 6148.569600127948, 6155.876882462236, 6170.259568851767, 6171.9074213887725, 6180.783672571439, 6324.620522768649, 6334.493240540838, 6344.524160687874, 6372.203474565679, 6387.680615969276, 5703.62926409782, 5972.940270597413, 6028.125921741411, 6033.487153591994, 6061.393860613904, 5457.972710136424, 5731.083026715705, 6109.266887973472, 6111.0, 6165.784197553064, 5681.77272909079, 5771.826845194651, 5917.615355001911, 5940.55445841555, 5965.075413310944, 4844.32179494864, 5889.617174883619, 5913.881722365614, 5930.28836539618, 5977.252843394575, 5760.539156867357, 5777.104548794943, 5782.508486778503, 5791.0734282514595, 5807.593468457168, 5784.074548072456, 5895.233619629421, 5910.586258961831, 5918.201042859331, 5933.732982015906, 5778.56660750437, 5813.593048486566, 5821.738021788945, 5875.588708790344, 5880.323762767336], "value": 5573.92325744164, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.12869028783615, 494.18212857720476, 502.6230327254557, 557.1809440123022, 643.5237923936286, 31033.862211691325, 39009.60990390095, 40084.59915400849, 40112.59887401125, 40272.39591406124, 45097.0, 45895.0, 45915.77042114793, 45920.540794592045, 45946.0, 8540.658373665046, 9637.277204209695, 9822.968588298198, 9823.999999999964, 9848.113669769718, 8691.695790647329, 11306.321620702744, 11535.884641153565, 11553.884461155407, 11556.653300401002, 11625.360605166712, 11768.705782355466, 11769.058475321954, 11774.47014884328, 11774.882251177463, 8411.285040771541, 8654.913450865473, 8657.480551166951, 11543.0, 11712.472938717778, 8651.480911145289, 11256.662300131009, 11532.481038353295, 11545.191836571443, 11558.30650160993, 38749.806250968846, 39513.01217469558, 39526.40710389346, 39572.40641390382, 39839.40240896389, 3259.4621887388553, 3349.815760133192, 3485.7559970802054, 3531.04661741331, 3566.0193446802077, 21933.451663708376, 27116.32209194786, 27970.860145699346, 29135.0, 29472.821087156488, 2700.986495067512, 2736.5621500559814, 2744.986275068632, 2784.290006048465, 2858.342581206334, 440248.0, 441468.7926560379, 444122.0, 444997.7750111261, 445837.7708111471, 34474.65525344764, 63708.36291637117, 65802.34197658057, 65828.6708566454, 65840.0, 35680.28639427249, 35817.8209108958, 35893.64106358955, 35922.0, 35933.46099808505, 14737.631559210891, 14990.700185996437, 15002.474913377979, 15013.69972600542, 15026.248687565578, 11327.830082548768, 11550.0, 12296.077794165474, 12479.5008199671, 12578.874211257771, 8183.263506284463, 8220.301274391624, 8239.95880020596, 8317.8336433271, 8325.333973282122, 3128.0772172209254, 3208.5508028875975, 3339.348826978716, 3376.561047063915, 3378.915527111793, 791.6041979010495, 904.6110172625703, 1026.5893642543042, 1052.3054783842626, 1063.250408462048, 29301.974430895243, 30086.398272034436, 30110.0, 30599.54100688447, 30865.382692346026, 996.9152622027209, 1029.264076185531, 1071.255477443185, 1123.393367581513, 1223.565634199864, 14702.852971470362, 14908.925455372872, 14911.776323355158, 15230.923845380923, 15233.93362464639, 642608.0, 659404.0, 660243.0, 661747.0, 666780.0, 61699.38300617026, 62394.688026559576, 62492.37507624865, 62650.37349626537, 62876.68561657162, 1368.5210176438168, 1498.7976623155953, 1531.961700957485, 1534.6700459401059, 1543.1744016950877, 25986.0, 29732.256693582833, 29868.726328104378, 30473.0, 30974.0, 53137.202941956675, 53741.65645858885, 54126.917461651334, 54414.18378724243, 54460.18778685454, 496516.51741742506, 501511.0, 502980.0, 504571.0, 506797.0, 21305.0, 22719.09123635036, 25964.480710386066, 26451.33871653147, 27377.86311068432, 43911.78044109759, 43935.34096988484, 44380.778096109316, 44843.87890302768, 45239.54760452485, 36108.27783444369, 37948.86153415402, 38031.188306379154, 38261.043473912265, 38830.0, 37130.88607341784, 37248.81375593105, 37346.0, 38065.809670951465, 38185.42721859119, 37043.0, 37415.43876841904, 37605.435918460695, 38133.0, 38160.04599885025, 31148.377032459677, 33355.666443335256, 33460.83269583636, 33656.83171584126, 33906.83046584751, 23927.521449570562, 24345.78271086403, 24382.75617243805, 24870.253892383258, 24998.625020624342], "value": 18726.493455214997, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3019231344996, 395.88915103770955, 396.5915107439338, 404.24406360106605, 410.3885211035558, 20016.89991550044, 21204.363869083914, 21733.565328693418, 21782.0, 22150.22474213403, 21596.1361545538, 22032.559348813014, 22337.664935025987, 22396.0, 22482.77517224827, 5769.538436925046, 6097.878042439149, 6118.357572454895, 6119.908201376983, 6122.571420000601, 4172.77049762263, 4195.46865394132, 5689.943100568983, 5771.740271687763, 6571.145751052357, 5749.626274292157, 6352.0, 6503.414692677658, 6510.609363438185, 6519.771807986722, 5776.0, 6165.845853853644, 6172.166757487771, 6173.104899789528, 6175.351588083254, 3872.9419058714143, 3882.9223415531533, 5386.13821788512, 5989.281286245635, 6021.0, 13566.186028838301, 13865.37605807741, 13868.93065534666, 14149.787753183607, 25061.37346566332, 2027.726256955307, 2049.405672355015, 2111.4193596761015, 2183.858049226795, 2198.9890050549643, 9789.314747967645, 10014.79970400588, 10346.948265258701, 10367.377957322586, 15380.077195368202, 1692.7545505901576, 1712.871534634895, 1757.3673477548073, 1799.3342463288536, 1828.1133650179652, 105845.0, 117136.0, 118514.0, 122727.3863630676, 129506.35246823894, 20211.898940505496, 20495.795042049685, 20659.380218593466, 20697.58604827895, 20914.026334996302, 34420.13949651229, 35133.297334053175, 35300.76447324331, 35302.0, 35304.94085177449, 10214.897851021395, 10958.493282149635, 11011.28426652281, 11035.006849383593, 11111.444427778579, 7400.333969942731, 8981.550922453851, 9025.142611451902, 9101.635934562675, 9118.544072796334, 8495.516838096351, 9578.90421095794, 9894.455804930798, 10030.699079027641, 10047.296689231684, 3154.542591324245, 3350.530925670408, 3723.3670276053135, 3791.3933770596573, 3863.7635956494214, 910.1853840812436, 957.8228027814737, 965.584798536622, 988.4464699768009, 1038.1383451735041, 20471.283505077252, 20712.58574828495, 21090.89454552748, 21202.363929082152, 21288.467788305108, 853.6073406233203, 930.1907340613709, 939.2298315381322, 981.563204374061, 1008.909198172168, 8735.868961965576, 8770.868436973451, 11257.04315133223, 11390.943045284721, 11467.0, 462085.0, 570081.0, 571871.0, 578659.0, 598678.0, 78914.0, 79285.0, 80953.19046809574, 121773.0, 133895.0, 1003.0771690045176, 1005.3013155856755, 1028.3367228137809, 1050.0, 1050.8108540462638, 18608.37136758319, 18849.869007859023, 18943.526411839815, 18956.715649264996, 19231.711524326867, 52246.865188088836, 53814.0, 54360.0, 54448.18327725008, 55375.0, 77474.2252577467, 80608.0, 81272.0, 82198.58900705459, 82684.58657706913, 7298.2701729826595, 8058.355331573577, 8293.502389856629, 8301.335893128415, 8312.584370781316, 9534.332596718163, 12238.938805305916, 12691.238525688488, 12769.170003949714, 13025.609231723063, 10321.812991506073, 10489.423081730272, 10522.0, 10942.835857462303, 11024.50389732448, 10595.258331917, 10695.411752353695, 10833.02502774754, 11118.387179013087, 11222.49498772541, 10962.835557466486, 11099.667009989713, 11168.832467513157, 11440.199186056896, 11498.48256828428, 8127.0, 8215.260626543639, 8273.0, 8444.451110677786, 8647.913520864711, 10432.634857779942, 10798.838017429902, 11079.778404432027, 11101.0, 11129.276597021168], "value": 10234.673574030734, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.8553251908543, 1579.1946107485187, 1608.9758653620195, 1845.6124213915077, 1979.4457551885469, 7713.92286077139, 8123.0252369715645, 8450.323974082074, 8711.651533938635, 8714.085021072775, 8436.198561136698, 8480.491170529756, 8582.098879617644, 8591.699290524833, 8596.742097737062, 2153.160267495678, 2425.7452967438385, 2457.3242358351417, 2468.814838887082, 2469.333280014395, 2209.5805093422678, 2326.8836558172106, 2508.7240403555684, 2516.2954372775653, 2521.9117330893423, 2415.577273977056, 2445.4131008557997, 2449.6570480132796, 2454.006127518358, 2455.3002394317623, 2138.101997161196, 2362.598358279097, 2433.4403087289934, 2445.193086281523, 2447.3269850791085, 2452.5953217719057, 2480.85114893107, 2481.702195736523, 2485.608059486693, 2486.539990101819, 8541.743747687578, 8648.264897483721, 8649.524276164808, 8662.436941598777, 8683.913160868373, 5068.949310506885, 5312.76092575835, 5343.438938911436, 5343.599230057723, 5346.625736198468, 3352.513885486591, 3422.6235114137426, 3486.093615659947, 3560.359135355635, 3588.982055089734, 978.8140253351911, 1019.2406657040478, 1044.3055368180228, 1051.8369652703843, 1066.807974564578, 11454.198206125575, 11515.078793696484, 11520.884791152148, 11532.077433805276, 11541.019013383873, 4781.521847815191, 4802.207635740134, 4910.607151427877, 4913.31213630092, 4946.307516947631, 9789.265805064648, 9827.459489728133, 9842.50787460624, 9855.507224638739, 9876.851847222297, 5403.054465468527, 5411.350637923396, 5413.512783849473, 5417.729113544307, 5418.187271909166, 3816.9427458588143, 4388.4733831940375, 4799.736014519165, 4850.0, 4977.054359671651, 3984.362501999724, 4000.959990400117, 4015.1166743316426, 4073.5111786585207, 4095.631393174569, 1342.2148043394723, 1347.4071408580194, 1359.2184493916038, 1361.8297712785902, 1376.5595009596832, 767.1714548287836, 906.1844340093968, 937.4141161773891, 944.9480278584743, 973.2651847854966, 5947.940520594825, 5986.2517185351835, 6042.0, 6094.634321940698, 6259.71831267594, 787.6534324897027, 912.9224015958586, 919.8850143732034, 972.5654659377503, 978.8874279457954, 3923.6468717815533, 3953.940690889639, 4000.4999375078114, 4055.797210139481, 4061.268971585084, 10251.28241023136, 10547.789044219073, 10810.135189184846, 10831.83752243717, 10871.239013268996, 10508.42203678805, 10790.974857388535, 10793.67618971432, 10810.35137891729, 10817.350958942488, 6646.833829154213, 8031.196880312039, 8093.919060809317, 8163.224493673091, 8268.0, 5820.854478638068, 5909.379515151019, 5927.733252003584, 5929.495992840484, 5932.525397968066, 9603.615855365986, 9708.6116555337, 10027.298089133983, 10065.094141527297, 10126.898731012596, 9131.863022054542, 10016.849747253942, 10068.546915388679, 10160.136388407069, 10305.587776489156, 6032.0, 6643.634600097041, 7029.332213439715, 7040.929590704028, 7068.045813815069, 8207.179282071746, 8219.383546233938, 8301.626426810688, 8316.376271779762, 8317.625706843328, 7168.0, 7216.9639151806, 7419.777406677808, 7477.14012888503, 7514.361279291322, 7151.213366529573, 7274.16347120088, 7311.159216690149, 7446.776596702108, 7456.478046536908, 6961.825954351182, 7008.053912721717, 7047.260037696173, 7048.154221493453, 7059.435245180476, 4125.525564560114, 4712.0, 4905.0, 5006.199008158823, 5074.543291103819, 4822.614190864652, 4896.510348965075, 4954.157793175241, 5061.620378471703, 5171.9482805172975], "value": 4584.787776670239, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.404044134159, 318.13308733700666, 323.8672144420788, 326.65864171940336, 327.37798183451434, 4753.932039562788, 5126.307948426961, 5206.687598744078, 5239.476052394763, 5280.363087442895, 4271.63691086258, 4884.99572762751, 5145.202493613496, 5154.819581314655, 5200.765965531551, 3351.698347148756, 3696.815159242034, 3734.7198960077967, 3996.260691772017, 4028.7784171870535, 3368.214846131554, 4012.8796136115816, 4044.373122166069, 4057.9391309130388, 4115.176964607075, 3453.6719011693976, 3580.7493475456727, 3699.907502312437, 3958.9208215835665, 3959.3467077932105, 3291.374638818617, 3333.93332133356, 3921.960780392202, 3967.6230758077936, 3980.661643760284, 3047.360054388581, 3431.7769344992494, 3442.8794992175153, 3449.6032956209856, 3457.4468085106514, 4760.047990401933, 4797.376341075638, 5024.221245706921, 5062.417821950486, 5063.949360506384, 4472.508024117345, 4522.68341216115, 4524.547545245482, 4541.864533866534, 4565.0, 5174.301469301634, 5458.972705136489, 5506.669599824023, 5515.503604675599, 5545.667259964415, 3401.8943843250795, 3538.575370955476, 3595.586507551639, 3825.1393436476847, 3839.1937693084487, 5394.73026348681, 5626.383731564842, 5630.887382252373, 5714.685622306851, 5734.419511219968, 3765.2657731742047, 3848.5958974307855, 3849.2493963676816, 3850.4994350734223, 3863.903402414906, 3265.853036613358, 3265.918352041218, 3897.472787127654, 3916.706247031484, 3945.5659877414037, 3733.7386382953478, 4280.777451509154, 4344.500382456058, 4459.929616891923, 4510.864674059718, 5207.0, 5486.725663716798, 5489.39616642169, 5515.889682206333, 5537.335519737658, 1195.8564972203392, 1280.103927250928, 1293.4438191577713, 1404.8524904885044, 1431.7329163690208, 796.74902405742, 905.4612505559181, 951.219999600335, 951.3816019587185, 962.6390103711109, 5446.945530544644, 5490.478404551562, 5495.450454954553, 5508.669479831223, 5514.145307477347, 5727.739897222605, 5756.7121643917635, 5902.6458412495385, 5915.231019967377, 6176.938230617637, 4835.347228124228, 5314.335708036496, 5394.705270734996, 5518.613697041249, 5556.555475561945, 2582.87085645717, 2945.4109178164454, 3205.7275131613605, 3234.450143475615, 3243.626982896997, 6233.594816336924, 6330.802944512991, 6364.840878978062, 6392.776252831221, 6420.935790642127, 6256.773426692178, 6275.529335299871, 6318.557700960889, 6324.841878953063, 6356.904646430307, 5573.749181286852, 5848.502877255396, 6104.938950610437, 6322.4942004639515, 6422.74309027643, 4774.498677638937, 5993.700314984146, 6105.419985101408, 6117.113018612187, 6123.663198524124, 4545.386402818326, 4980.925286120783, 5458.727063646722, 5473.75368108444, 5484.945150548443, 5073.3911930568565, 5588.552915766811, 5660.462256085665, 5677.347105082803, 5887.587868849139, 3441.8451169696923, 3716.312482190803, 3828.138668799609, 3836.731428799957, 3843.231353729321, 5116.718580478109, 5180.663256888291, 5181.145111056633, 5686.943130568641, 5704.543636508986, 4626.37543931565, 5483.588730845123, 5522.723863806874, 5533.889322213614, 5566.471185237396, 5130.81991142032, 5459.918101228405, 5490.505854472957, 5493.626593351662, 5520.282363292747, 5425.755840987244, 5484.451554844483, 5522.5581953442825, 5533.3083364579425, 5535.086710692691, 4343.826246950087, 5081.593472522115, 5281.0, 5452.536534394462, 5604.0298641719955, 4634.698744581591, 5137.409197942135, 5267.473252674702, 5348.465153484621, 5531.557475401878], "value": 4083.82473881727, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.8230268978722, 712.4264966701803, 730.9451791115664, 741.4465652264538, 755.4636208292112, 14582.125072495657, 14948.476803311887, 15070.547883563497, 15078.0, 15082.472113476031, 14929.104253744783, 15456.304466299016, 15471.613209669764, 15472.767908481383, 15571.98782079164, 742.3467348733108, 767.612355760343, 939.1547607153582, 953.5947222430474, 961.2406199102701, 926.865604487349, 960.5581432541039, 965.3918031640056, 966.4732720667218, 967.5017366056486, 986.856905748666, 1011.8988101189896, 1012.2964539644913, 1012.4178597306542, 1012.4431562640555, 783.8471498057881, 959.1415682963708, 988.1255089246033, 1000.8398656215007, 1001.114014097529, 742.2354974376401, 750.6434443639282, 751.0274194917595, 751.4101430377127, 751.6880494594736, 12455.626331210078, 15022.699546009128, 15082.396704131821, 15093.773593396108, 15118.924405378013, 12230.205036672676, 12252.142350035501, 12408.131430799847, 12462.376881155906, 12581.245125292511, 9860.852087218698, 10035.548400322003, 10075.0, 10438.321509101885, 10867.728306792314, 3467.358538670354, 3481.791092534456, 3544.326577950181, 3549.562427216981, 3567.3935430976794, 84141.57929210376, 84691.57654211689, 85429.0, 85600.57199713962, 86177.0, 9253.074692530694, 9279.768005799773, 9358.0, 9452.385594936308, 9918.851217231748, 20516.487087822923, 20777.896110519654, 20815.959202039838, 20826.166953322, 20842.895785520974, 6122.5408094392205, 7249.782506524813, 7352.19125896151, 7503.699852005859, 7544.245575442522, 3415.8633654653536, 4043.1913617276664, 4082.9591704082577, 4098.467199264136, 4314.870553883388, 3302.612902580922, 3304.273059926812, 3474.6004209516227, 3825.7895815729844, 3841.2125514269505, 682.576802382526, 686.1663079358603, 692.1866806502339, 698.6541661877403, 711.2283172757512, 501.4709481497056, 594.70859278953, 597.2981746447906, 616.7101462312706, 621.1242148570584, 8040.959795201104, 8058.435909486397, 8195.549244791475, 8325.583720814057, 8336.249737523653, 585.8213244960328, 604.4167378479788, 607.5686262753494, 612.9264488261348, 643.5237923936254, 3870.322693528621, 3871.457995880579, 3883.3204189266767, 4007.877794217624, 4010.258102251093, 85750.0, 87357.0, 88783.0, 88815.66776498358, 91844.54077729568, 53272.73363633157, 68574.0, 71460.64269678722, 74355.0, 78277.60861195657, 65355.36611584843, 69706.95439568309, 69764.0, 72856.271437286, 75735.0, 8076.353891688768, 8082.393820463371, 8211.71259005932, 8219.712310069119, 8355.707550235713, 42224.78887605542, 42661.36007959945, 43277.0, 43966.340504893094, 44003.33994990142, 59226.70386648039, 63227.0, 65950.34049659634, 67140.97154170765, 67386.66306668632, 10753.623623173151, 10841.67474975752, 10845.945770271099, 10974.231803773659, 11304.943475282571, 9676.70969870905, 11929.28424294545, 11960.222585531912, 12056.578019769266, 12601.495940162291, 11280.661580152608, 11305.547778088785, 11622.883771162518, 11735.0, 11765.999890009103, 9290.88509378879, 11069.446527673745, 11473.196876218584, 11479.196456247982, 11511.481983310938, 11178.720531986766, 11198.720031998939, 11226.0, 11343.829842552534, 11519.654410367702, 9509.61961521559, 9731.0, 9746.9025309746, 9892.432571893636, 10128.189744820544, 9600.135987761136, 9619.422834629944, 9680.225581953286, 9718.319717619699, 9899.554520046759], "value": 6891.779250472428, "unit": "score"}], "name": "dromaeo_css", "value": 7187.464308801756, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 108.0, 112.0, 112.0, 111.0, 113.0, 112.0, 105.0, 114.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 123.0, 143.0, 168.0, 118.0, 144.0, 139.0, 119.0, 171.0, 116.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 184.0, 188.0, 187.0, 186.0, 182.0, 184.0, 187.0, 185.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 90.0, 92.0, 57.0, 56.0, 61.0, 82.0, 86.0, 55.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 128.0, 178.0, 93.0, 79.0, 82.0, 115.0, 78.0, 82.0, 119.0], "value": 104.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 87.0, 85.0, 89.0, 85.0, 88.0, 85.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 92.0, 94.0, 95.0, 94.0, 94.0, 97.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 86.0, 84.0, 85.0, 86.0, 87.0, 85.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 60.0, 58.0, 57.0, 58.0, 59.0, 78.0, 60.0, 58.0], "value": 60.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 73.0, 97.0, 110.0, 108.0, 67.0, 68.0, 67.0, 68.0, 68.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 83.0, 82.0, 82.0, 81.0, 81.0, 83.0, 98.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 148.0, 149.0, 155.0, 135.0, 135.0, 153.0, 157.0, 172.0, 140.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 167.0, 168.0, 169.0, 169.0, 170.0, 169.0, 168.0, 164.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 70.0, 68.0, 67.0, 68.0, 69.0, 68.0, 66.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1488.2, "alertThreshold": 2.0}]} 05:37:02 INFO - Return code: 0 05:37:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:37:02 ERROR - # TBPL SUCCESS # 05:37:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:37:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:37:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:37:02 INFO - 2016-04-29 05:37:02.196 screenresolution[1951:17868] starting screenresolution argv=screenresolution get 05:37:02 INFO - 2016-04-29 05:37:02.199 screenresolution[1951:17868] Display 0: 1600x1200x32@60 05:37:02 INFO - 2016-04-29 05:37:02.205 screenresolution[1952:17871] starting screenresolution argv=screenresolution list 05:37:02 INFO - Available Modes on Display 0 05:37:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:37:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:37:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:37:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:37:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:37:02 INFO - Intel Iris: 05:37:02 INFO - Chipset Model: Intel Iris 05:37:02 INFO - Type: GPU 05:37:02 INFO - Bus: Built-In 05:37:02 INFO - VRAM (Dynamic, Max): 1536 MB 05:37:02 INFO - Vendor: Intel (0x8086) 05:37:02 INFO - Device ID: 0x0a2e 05:37:02 INFO - Revision ID: 0x0009 05:37:02 INFO - Displays: 05:37:02 INFO - Display: 05:37:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:37:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:37:02 INFO - Display Serial Number: 1600x1200 60 05:37:02 INFO - Main Display: Yes 05:37:02 INFO - Mirror: Off 05:37:02 INFO - Online: Yes 05:37:02 INFO - Rotation: Supported 05:37:02 INFO - Return code: 0 05:37:02 INFO - Running post-action listener: _resource_record_post_action 05:37:02 INFO - Running post-run listener: _resource_record_post_run 05:37:03 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 864256; Write bytes: 697350656; Read time: 11; Write time: 2052 05:37:03 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376717824; Write bytes: 371787264; Read time: 28345; Write time: 869 05:37:03 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 864256; Write bytes: 298116096; Read time: 11; Write time: 1129 05:37:03 INFO - Running post-run listener: _upload_blobber_files 05:37:03 INFO - Blob upload gear active. 05:37:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:37:03 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:37:03 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:37:03 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:37:03 INFO - (blobuploader) - INFO - Open directory for files ... 05:37:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:37:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:37:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:04 INFO - (blobuploader) - INFO - Done attempting. 05:37:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:37:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:37:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:37:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:37:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:37:06 INFO - (blobuploader) - INFO - Done attempting. 05:37:06 INFO - (blobuploader) - INFO - Iteration through files over. 05:37:06 INFO - Return code: 0 05:37:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:37:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:37:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6cca724395cb8c1a14863f158cb2600104f0e89c94f0eb3d627a8ae887eff0d27f0b472a64010f267cffa9c0fc642b52be2a15b286e1ed280670b920ae9467e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402889e53b9e24ca0424ea4b93ff8fab59a860c631cffe4ae6db1d1423d653dae5d8b5c48e5dda7177275168cb06f7267e50dc5dbb258e953b6336906051c452"} 05:37:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:37:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:37:06 INFO - Contents: 05:37:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6cca724395cb8c1a14863f158cb2600104f0e89c94f0eb3d627a8ae887eff0d27f0b472a64010f267cffa9c0fc642b52be2a15b286e1ed280670b920ae9467e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402889e53b9e24ca0424ea4b93ff8fab59a860c631cffe4ae6db1d1423d653dae5d8b5c48e5dda7177275168cb06f7267e50dc5dbb258e953b6336906051c452"} 05:37:06 INFO - Running post-run listener: copy_logs_to_upload_dir 05:37:06 INFO - Copying logs to upload dir... 05:37:06 INFO - mkdir: /builds/slave/test/build/upload/logs 05:37:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.789314 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-04-29 05:37:06.085805) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 05:37:06.089473) ========= 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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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/6cca724395cb8c1a14863f158cb2600104f0e89c94f0eb3d627a8ae887eff0d27f0b472a64010f267cffa9c0fc642b52be2a15b286e1ed280670b920ae9467e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402889e53b9e24ca0424ea4b93ff8fab59a860c631cffe4ae6db1d1423d653dae5d8b5c48e5dda7177275168cb06f7267e50dc5dbb258e953b6336906051c452"} build_url:https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011173 build_url: 'https://queue.taskcluster.net/v1/task/W7MKWzaIRcqOBGjrngFAAw/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/6cca724395cb8c1a14863f158cb2600104f0e89c94f0eb3d627a8ae887eff0d27f0b472a64010f267cffa9c0fc642b52be2a15b286e1ed280670b920ae9467e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/402889e53b9e24ca0424ea4b93ff8fab59a860c631cffe4ae6db1d1423d653dae5d8b5c48e5dda7177275168cb06f7267e50dc5dbb258e953b6336906051c452"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 05:37:06.123780) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:37:06.124340) ========= 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.oMIJ7T6BIN/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.SgW0q7M74k/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005214 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 05:37:06.174988) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 05:37:06.175512) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 05:37:06.176157) ========= ========= Total master_lag: 8.23 =========