builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0079 starttime: 1461235640.68 results: success (0) buildid: 20160421015807 builduid: 376727a80f044862823e13116b89cd44 revision: 4e3ad95d689a5beabf3c1f41d958794fe00e3767 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:20.677962) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:20.678370) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:20.678668) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:20.704366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:20.704644) ========= 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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-21 03:47:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 118M=0s 2016-04-21 03:47:21 (118 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098844 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:20.823534) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:20.823822) ========= 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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.030613 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:20.870984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 03:47:20.871295) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4e3ad95d689a5beabf3c1f41d958794fe00e3767 --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 4e3ad95d689a5beabf3c1f41d958794fe00e3767 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.51Mx64yTIP/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.7AHGZ3aETQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-21 03:47:21,374 truncating revision to first 12 chars 2016-04-21 03:47:21,375 Setting DEBUG logging. 2016-04-21 03:47:21,375 attempt 1/10 2016-04-21 03:47:21,375 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4e3ad95d689a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 03:47:22,888 unpacking tar archive at: mozilla-central-4e3ad95d689a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.763564 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 03:47:22.653914) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:22.654229) ========= script_repo_revision: 4e3ad95d689a5beabf3c1f41d958794fe00e3767 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:22.657616) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:22.657886) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 03:47:22.672017) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-04-21 03:47:22.672283) ========= /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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:47:23 INFO - MultiFileLogger online at 20160421 03:47:23 in /builds/slave/test 03:47:23 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 03:47:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:47:23 INFO - {'append_to_log': False, 03:47:23 INFO - 'base_work_dir': '/builds/slave/test', 03:47:23 INFO - 'blob_upload_branch': 'Firefox', 03:47:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:47:23 INFO - 'branch': 'Firefox', 03:47:23 INFO - 'buildbot_json_path': 'buildprops.json', 03:47:23 INFO - 'config_files': ('talos/mac_config.py',), 03:47:23 INFO - 'default_actions': ('clobber', 03:47:23 INFO - 'read-buildbot-config', 03:47:23 INFO - 'download-and-extract', 03:47:23 INFO - 'populate-webroot', 03:47:23 INFO - 'create-virtualenv', 03:47:23 INFO - 'install', 03:47:23 INFO - 'run-tests'), 03:47:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:47:23 INFO - 'download_minidump_stackwalk': True, 03:47:23 INFO - 'download_symbols': 'ondemand', 03:47:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:47:23 INFO - 'tooltool.py': '/tools/tooltool.py', 03:47:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:47:23 INFO - '/tools/misc-python/virtualenv.py')}, 03:47:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:47:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:47:23 INFO - 'installer_path': 'installer.exe', 03:47:23 INFO - 'log_level': 'info', 03:47:23 INFO - 'log_name': 'talos', 03:47:23 INFO - 'log_to_console': True, 03:47:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:47:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:47:23 INFO - 'opt_config_files': (), 03:47:23 INFO - 'pip_index': False, 03:47:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:47:23 INFO - 'cmd': ('bash', 03:47:23 INFO - '-c', 03:47:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:47:23 INFO - 'enabled': True, 03:47:23 INFO - 'halt_on_failure': False, 03:47:23 INFO - 'name': 'check_screen_resolution'},), 03:47:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:47:23 INFO - 'cmd': ('bash', 03:47:23 INFO - '-c', 03:47:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:47:23 INFO - 'enabled': True, 03:47:23 INFO - 'halt_on_failure': False, 03:47:23 INFO - 'name': 'check_screen_resolution'},), 03:47:23 INFO - 'run_cmd_checks_enabled': True, 03:47:23 INFO - 'sps_profile': False, 03:47:23 INFO - 'sps_profile_interval': 0, 03:47:23 INFO - 'suite': 'dromaeojs', 03:47:23 INFO - 'system_bits': '32', 03:47:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:47:23 INFO - 'title': 't-yosemite-r7-0079', 03:47:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:47:23 INFO - 'use_talos_json': True, 03:47:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:47:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:47:23 INFO - 'work_dir': 'build'} 03:47:23 INFO - ##### 03:47:23 INFO - ##### Running clobber step. 03:47:23 INFO - ##### 03:47:23 INFO - Running pre-action listener: _resource_record_pre_action 03:47:23 INFO - Running main action method: clobber 03:47:23 INFO - rmtree: /builds/slave/test/build 03:47:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:47:24 INFO - Running post-action listener: _resource_record_post_action 03:47:24 INFO - ##### 03:47:24 INFO - ##### Running read-buildbot-config step. 03:47:24 INFO - ##### 03:47:24 INFO - Running pre-action listener: _resource_record_pre_action 03:47:24 INFO - Running main action method: read_buildbot_config 03:47:24 INFO - Using buildbot properties: 03:47:24 INFO - { 03:47:24 INFO - "project": "", 03:47:24 INFO - "product": "firefox", 03:47:24 INFO - "script_repo_revision": "production", 03:47:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 03:47:24 INFO - "repository": "", 03:47:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 03:47:24 INFO - "buildid": "20160421015807", 03:47:24 INFO - "pgo_build": "False", 03:47:24 INFO - "basedir": "/builds/slave/test", 03:47:24 INFO - "buildnumber": 221, 03:47:24 INFO - "slavename": "t-yosemite-r7-0079", 03:47:24 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:47:24 INFO - "platform": "yosemite_r7", 03:47:24 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:47:24 INFO - "branch": "mozilla-central", 03:47:24 INFO - "repo_path": "mozilla-central", 03:47:24 INFO - "revision": "4e3ad95d689a5beabf3c1f41d958794fe00e3767", 03:47:24 INFO - "stage_platform": "macosx64", 03:47:24 INFO - "builduid": "376727a80f044862823e13116b89cd44", 03:47:24 INFO - "slavebuilddir": "test" 03:47:24 INFO - } 03:47:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 03:47:24 INFO - Running post-action listener: _resource_record_post_action 03:47:24 INFO - ##### 03:47:24 INFO - ##### Running download-and-extract step. 03:47:24 INFO - ##### 03:47:24 INFO - Running pre-action listener: _resource_record_pre_action 03:47:24 INFO - Running main action method: download_and_extract 03:47:24 INFO - mkdir: /builds/slave/test/build/tests 03:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:24 INFO - https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:47:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 03:47:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 03:47:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 03:47:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 03:47:25 INFO - Downloaded 1199 bytes. 03:47:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 03:47:25 INFO - Using the following test package requirements: 03:47:25 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 03:47:25 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:47:25 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 03:47:25 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:47:25 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 03:47:25 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:47:25 INFO - u'jsshell-mac.zip'], 03:47:25 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:47:25 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 03:47:25 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 03:47:25 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:47:25 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 03:47:25 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:47:25 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 03:47:25 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:47:25 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 03:47:25 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 03:47:25 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 03:47:25 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:47: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')]} 03:47:25 INFO - https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:47:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:47:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:47:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:47:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:47:27 INFO - Downloaded 18099594 bytes. 03:47:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:47:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:47:30 INFO - Return code: 0 03:47:30 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:47:30 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')]} 03:47:30 INFO - https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:47:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:47:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:47:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 03:47:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:47:31 INFO - Downloaded 18099594 bytes. 03:47:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:47:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:47:34 INFO - Return code: 0 03:47:34 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')]} 03:47:34 INFO - https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 03:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 03:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 03:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:47:35 INFO - Downloaded 10845952 bytes. 03:47:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:47:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:47:36 INFO - Return code: 0 03:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:36 INFO - https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:47:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 03:47:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 03:47:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 03:47:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 03:47:39 INFO - Downloaded 110046502 bytes. 03:47:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 03:47:39 INFO - mkdir: /builds/slave/test/properties 03:47:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:47:39 INFO - Writing to file /builds/slave/test/properties/build_url 03:47:39 INFO - Contents: 03:47:39 INFO - build_url:https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 03:47:40 INFO - Running post-action listener: _resource_record_post_action 03:47:40 INFO - Running post-action listener: set_extra_try_arguments 03:47:40 INFO - ##### 03:47:40 INFO - ##### Running populate-webroot step. 03:47:40 INFO - ##### 03:47:40 INFO - Running pre-action listener: _resource_record_pre_action 03:47:40 INFO - Running main action method: populate_webroot 03:47:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:47:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:47:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:47:40 INFO - '--tppagecycles', 03:47:40 INFO - '7'], 03:47:40 INFO - 'tests': ['tsvgm']}}, 03:47:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:47:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:47:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:47:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:47:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:40 INFO - 'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:47:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:40 INFO - 'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['damp', 'tps']}, 03:47:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:40 INFO - 'tests': ['damp', 'tps']}, 03:47:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['dromaeo_dom']}, 03:47:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:47:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['a11yr', 03:47:40 INFO - 'ts_paint', 03:47:40 INFO - 'tpaint', 03:47:40 INFO - 'sessionrestore', 03:47:40 INFO - 'sessionrestore_no_auto_restore', 03:47:40 INFO - 'tabpaint']}, 03:47:40 INFO - 'other-e10s': {'tests': ['a11yr', 03:47:40 INFO - 'ts_paint', 03:47:40 INFO - 'tpaint', 03:47:40 INFO - 'sessionrestore', 03:47:40 INFO - 'sessionrestore_no_auto_restore', 03:47:40 INFO - 'tabpaint']}, 03:47:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:47:40 INFO - 'ts_paint', 03:47:40 INFO - 'tpaint', 03:47:40 INFO - 'sessionrestore', 03:47:40 INFO - 'sessionrestore_no_auto_restore', 03:47:40 INFO - 'tabpaint']}, 03:47:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:47:40 INFO - 'ts_paint', 03:47:40 INFO - 'tpaint', 03:47:40 INFO - 'sessionrestore', 03:47:40 INFO - 'sessionrestore_no_auto_restore', 03:47:40 INFO - 'tabpaint']}, 03:47:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['a11yr', 03:47:40 INFO - 'ts_paint', 03:47:40 INFO - 'tpaint', 03:47:40 INFO - 'sessionrestore', 03:47:40 INFO - 'sessionrestore_no_auto_restore', 03:47:40 INFO - 'tabpaint']}, 03:47:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['a11yr', 03:47:40 INFO - 'ts_paint', 03:47:40 INFO - 'tpaint', 03:47:40 INFO - 'sessionrestore', 03:47:40 INFO - 'sessionrestore_no_auto_restore', 03:47:40 INFO - 'tabpaint']}, 03:47:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['tsvgx', 03:47:40 INFO - 'tsvgr_opacity', 03:47:40 INFO - 'tart', 03:47:40 INFO - 'tscrollx', 03:47:40 INFO - 'cart']}, 03:47:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:47:40 INFO - 'tsvgr_opacity', 03:47:40 INFO - 'tart', 03:47:40 INFO - 'tscrollx', 03:47:40 INFO - 'cart']}, 03:47:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:40 INFO - 'talos_options': ['--disable-e10s'], 03:47:40 INFO - 'tests': ['tp5o']}, 03:47:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:40 INFO - 'tests': ['tp5o']}, 03:47:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:40 INFO - 'talos_options': ['--disable-e10s', 03:47:40 INFO - '--xperf_path', 03:47:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:40 INFO - 'tests': ['tp5n']}, 03:47:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:47:40 INFO - 'talos_options': ['--xperf_path', 03:47:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:47:40 INFO - 'tests': ['tp5n']}}, 03:47:40 INFO - 'talos.zip': {'path': '', 03:47:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:47:40 INFO - Running post-action listener: _resource_record_post_action 03:47:40 INFO - ##### 03:47:40 INFO - ##### Running create-virtualenv step. 03:47:40 INFO - ##### 03:47:40 INFO - Running pre-action listener: _resource_record_pre_action 03:47:40 INFO - Running main action method: create_virtualenv 03:47:40 INFO - Creating virtualenv /builds/slave/test/build/venv 03:47:40 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 03:47:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:47:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:47:40 INFO - Using real prefix '/tools/python27' 03:47:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:47:41 INFO - Installing distribute.............................................................................................................................................................................................done. 03:47:44 INFO - Installing pip.................done. 03:47:44 INFO - Return code: 0 03:47:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106593a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00058ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, '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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:47:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:44 INFO - 'HOME': '/Users/cltbld', 03:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:44 INFO - 'LOGNAME': 'cltbld', 03:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:44 INFO - 'MOZ_NO_REMOTE': '1', 03:47:44 INFO - 'NO_EM_RESTART': '1', 03:47:44 INFO - 'PAGER': '/bin/cat', 03:47:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:44 INFO - 'PWD': '/builds/slave/test', 03:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:44 INFO - 'SHELL': '/bin/bash', 03:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:47:44 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:44 INFO - 'USER': 'cltbld', 03:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:44 INFO - 'XPC_FLAGS': '0x0', 03:47:44 INFO - 'XPC_SERVICE_NAME': '0', 03:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:44 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 03:47:44 INFO - Cleaning up... 03:47:44 INFO - Return code: 0 03:47:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106593a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00058ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, '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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:47:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 03:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:44 INFO - 'HOME': '/Users/cltbld', 03:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:44 INFO - 'LOGNAME': 'cltbld', 03:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:44 INFO - 'MOZ_NO_REMOTE': '1', 03:47:44 INFO - 'NO_EM_RESTART': '1', 03:47:44 INFO - 'PAGER': '/bin/cat', 03:47:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:44 INFO - 'PWD': '/builds/slave/test', 03:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:44 INFO - 'SHELL': '/bin/bash', 03:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:47:44 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:44 INFO - 'USER': 'cltbld', 03:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:44 INFO - 'XPC_FLAGS': '0x0', 03:47:44 INFO - 'XPC_SERVICE_NAME': '0', 03:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:45 INFO - Downloading/unpacking psutil>=0.7.1 03:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:47:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:48 INFO - Installing collected packages: psutil 03:47:48 INFO - Running setup.py install for psutil 03:47:48 INFO - building 'psutil._psutil_osx' extension 03:47:48 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 03:47:48 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 03:47:48 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 03:47:48 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 03:47:48 INFO - building 'psutil._psutil_posix' extension 03:47:48 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 03:47:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:47:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:47:48 INFO - ^ 03:47:49 INFO - 1 warning generated. 03:47:49 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 03:47:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:47:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:47:49 INFO - Successfully installed psutil 03:47:49 INFO - Cleaning up... 03:47:49 INFO - Return code: 0 03:47:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:49 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 0x106593a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00058ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, '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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:47:49 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 03:47:49 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 03:47:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:47:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:49 INFO - 'HOME': '/Users/cltbld', 03:47:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:49 INFO - 'LOGNAME': 'cltbld', 03:47:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:49 INFO - 'MOZ_NO_REMOTE': '1', 03:47:49 INFO - 'NO_EM_RESTART': '1', 03:47:49 INFO - 'PAGER': '/bin/cat', 03:47:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:49 INFO - 'PWD': '/builds/slave/test', 03:47:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:49 INFO - 'SHELL': '/bin/bash', 03:47:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:47:49 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:47:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:49 INFO - 'USER': 'cltbld', 03:47:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:49 INFO - 'XPC_FLAGS': '0x0', 03:47:49 INFO - 'XPC_SERVICE_NAME': '0', 03:47:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:47: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 03:47: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 03:47: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 03:47: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 03:47: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 03:47: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 03:47:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:47:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:47:52 INFO - Installing collected packages: mozsystemmonitor 03:47:52 INFO - Running setup.py install for mozsystemmonitor 03:47:52 INFO - Successfully installed mozsystemmonitor 03:47:52 INFO - Cleaning up... 03:47:52 INFO - Return code: 0 03:47:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:47:52 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')]} 03:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:52 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')]} 03:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:52 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 0x106593a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00058ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, '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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:47:52 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 03:47:52 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 03:47:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:47:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:52 INFO - 'HOME': '/Users/cltbld', 03:47:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:52 INFO - 'LOGNAME': 'cltbld', 03:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:52 INFO - 'MOZ_NO_REMOTE': '1', 03:47:52 INFO - 'NO_EM_RESTART': '1', 03:47:52 INFO - 'PAGER': '/bin/cat', 03:47:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:52 INFO - 'PWD': '/builds/slave/test', 03:47:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:52 INFO - 'SHELL': '/bin/bash', 03:47:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:47:52 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:47:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:52 INFO - 'USER': 'cltbld', 03:47:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:52 INFO - 'XPC_FLAGS': '0x0', 03:47:52 INFO - 'XPC_SERVICE_NAME': '0', 03:47:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:52 INFO - Downloading/unpacking blobuploader==1.2.4 03:47:52 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 03:47:52 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 03:47:52 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 03:47:52 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 03:47:52 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 03:47:52 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 03:47:54 INFO - Downloading blobuploader-1.2.4.tar.gz 03:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:47:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:47:54 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 03:47:54 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 03:47:54 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 03:47:54 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 03:47:54 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 03:47:54 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 03:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:47:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:55 INFO - Downloading docopt-0.6.1.tar.gz 03:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:47:56 INFO - Installing collected packages: blobuploader, requests, docopt 03:47:56 INFO - Running setup.py install for blobuploader 03:47:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:47:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:47:56 INFO - Running setup.py install for requests 03:47:56 INFO - Running setup.py install for docopt 03:47:56 INFO - Successfully installed blobuploader requests docopt 03:47:56 INFO - Cleaning up... 03:47:57 INFO - Return code: 0 03:47:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:57 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')]} 03:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:57 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')]} 03:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:47:57 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 0x106593a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00058ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, '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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:47:57 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 03:47:57 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 03:47:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:47:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:57 INFO - 'HOME': '/Users/cltbld', 03:47:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:57 INFO - 'LOGNAME': 'cltbld', 03:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:57 INFO - 'MOZ_NO_REMOTE': '1', 03:47:57 INFO - 'NO_EM_RESTART': '1', 03:47:57 INFO - 'PAGER': '/bin/cat', 03:47:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:57 INFO - 'PWD': '/builds/slave/test', 03:47:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:57 INFO - 'SHELL': '/bin/bash', 03:47:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:47:57 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:47:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:57 INFO - 'USER': 'cltbld', 03:47:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:57 INFO - 'XPC_FLAGS': '0x0', 03:47:57 INFO - 'XPC_SERVICE_NAME': '0', 03:47:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:47:57 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-5xIz4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:47:57 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-SklERo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:47:57 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-9qX2IN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:47:57 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-sVYCme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:47:57 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-dapOQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:47:57 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-bZu17p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:47:57 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-BE4bzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:47:57 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-tqGo14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:47:58 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-q2jW05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:47:58 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-iZjybF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:47:58 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-vuCng6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:47:58 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-8idBSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:47:58 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-IiKdg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:47:58 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-SiEvOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:58 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-_vIpHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:47:58 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-t3pCUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:47:58 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-FxXPq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:47:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:47:59 INFO - Running setup.py install for manifestparser 03:47:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:47:59 INFO - Running setup.py install for mozcrash 03:47:59 INFO - Running setup.py install for mozdebug 03:47:59 INFO - Running setup.py install for mozdevice 03:47:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:47:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:47:59 INFO - Running setup.py install for mozfile 03:47:59 INFO - Running setup.py install for mozhttpd 03:47:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:47:59 INFO - Running setup.py install for mozinfo 03:48:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Running setup.py install for mozInstall 03:48:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Running setup.py install for mozleak 03:48:00 INFO - Running setup.py install for mozlog 03:48:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Running setup.py install for moznetwork 03:48:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Running setup.py install for mozprocess 03:48:00 INFO - Running setup.py install for mozprofile 03:48:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:48:00 INFO - Running setup.py install for mozrunner 03:48:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:48:01 INFO - Running setup.py install for mozscreenshot 03:48:01 INFO - Running setup.py install for moztest 03:48:01 INFO - Running setup.py install for mozversion 03:48:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:48:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:48:01 INFO - Cleaning up... 03:48:01 INFO - Return code: 0 03:48:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:48:01 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')]} 03:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:01 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')]} 03:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:01 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 0x106593a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00058ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, '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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:48:01 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 03:48:01 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 03:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:48:01 INFO - 'HOME': '/Users/cltbld', 03:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:48:01 INFO - 'LOGNAME': 'cltbld', 03:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:01 INFO - 'MOZ_NO_REMOTE': '1', 03:48:01 INFO - 'NO_EM_RESTART': '1', 03:48:01 INFO - 'PAGER': '/bin/cat', 03:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:01 INFO - 'PWD': '/builds/slave/test', 03:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:48:01 INFO - 'SHELL': '/bin/bash', 03:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:48:01 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:48:01 INFO - 'USER': 'cltbld', 03:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:01 INFO - 'XPC_FLAGS': '0x0', 03:48:01 INFO - 'XPC_SERVICE_NAME': '0', 03:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:48:01 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-by4aOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:48:01 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)) 03:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:48:01 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-fyuizd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:48:02 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)) 03:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:48:02 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-ewGu3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:48:02 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)) 03:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:48:02 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-XuGMMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:48:02 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)) 03:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:48:02 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-jxDacw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:48:02 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)) 03:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:48:02 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-KNU2MM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:48:02 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)) 03:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:48:02 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-pwG6je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:48:02 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)) 03:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:48:02 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-AJJRSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:48:02 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)) 03:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:48:02 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-03iNcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:48:02 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)) 03:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:48:02 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-zg4CIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:48:02 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)) 03:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:48:02 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-G5_t2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:48:03 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)) 03:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:48:03 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Z3tCDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:48:03 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)) 03:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:48:03 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-o7hxy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:48:03 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)) 03:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:48:03 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-kQrAE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:48:03 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)) 03:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:48:03 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-yxb7aT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:48:03 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)) 03:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:48:03 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-XQnM3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:48:03 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)) 03:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:48:03 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-pYhatr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:48:03 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)) 03:48:03 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)) 03:48:03 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)) 03:48:03 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)) 03:48:03 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)) 03:48:03 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)) 03:48:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:06 INFO - Downloading blessings-1.6.tar.gz 03:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:48:06 INFO - Installing collected packages: blessings 03:48:06 INFO - Running setup.py install for blessings 03:48:06 INFO - Successfully installed blessings 03:48:06 INFO - Cleaning up... 03:48:06 INFO - Return code: 0 03:48:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:48:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:48:06 INFO - Reading from file tmpfile_stdout 03:48:06 INFO - Current package versions: 03:48:06 INFO - blessings == 1.6 03:48:06 INFO - blobuploader == 1.2.4 03:48:06 INFO - docopt == 0.6.1 03:48:06 INFO - manifestparser == 1.1 03:48:06 INFO - mozInstall == 1.12 03:48:06 INFO - mozcrash == 0.17 03:48:06 INFO - mozdebug == 0.1 03:48:06 INFO - mozdevice == 0.48 03:48:06 INFO - mozfile == 1.2 03:48:06 INFO - mozhttpd == 0.7 03:48:06 INFO - mozinfo == 0.9 03:48:06 INFO - mozleak == 0.1 03:48:06 INFO - mozlog == 3.1 03:48:06 INFO - moznetwork == 0.27 03:48:06 INFO - mozprocess == 0.22 03:48:06 INFO - mozprofile == 0.28 03:48:06 INFO - mozrunner == 6.11 03:48:06 INFO - mozscreenshot == 0.1 03:48:06 INFO - mozsystemmonitor == 0.0 03:48:06 INFO - moztest == 0.7 03:48:06 INFO - mozversion == 1.4 03:48:06 INFO - psutil == 3.1.1 03:48:06 INFO - requests == 1.2.3 03:48:06 INFO - wsgiref == 0.1.2 03:48:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:48: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')]} 03:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48: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')]} 03:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:06 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 0x106593a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00058ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, '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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:48:06 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 03:48:06 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 03:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:48:06 INFO - 'HOME': '/Users/cltbld', 03:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:48:06 INFO - 'LOGNAME': 'cltbld', 03:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:06 INFO - 'MOZ_NO_REMOTE': '1', 03:48:06 INFO - 'NO_EM_RESTART': '1', 03:48:06 INFO - 'PAGER': '/bin/cat', 03:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:06 INFO - 'PWD': '/builds/slave/test', 03:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:48:06 INFO - 'SHELL': '/bin/bash', 03:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:48:06 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:48:06 INFO - 'USER': 'cltbld', 03:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:06 INFO - 'XPC_FLAGS': '0x0', 03:48:06 INFO - 'XPC_SERVICE_NAME': '0', 03:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:06 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)) 03:48:06 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)) 03:48:06 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)) 03:48:06 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)) 03:48:06 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)) 03:48:06 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)) 03:48:06 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)) 03:48:06 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)) 03:48:06 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)) 03:48:06 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)) 03:48:06 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)) 03:48:06 INFO - Cleaning up... 03:48:06 INFO - Return code: 0 03:48:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:48: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')]} 03:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48: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')]} 03:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:06 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 0x106593a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00058ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, '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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:48:06 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 03:48:06 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 03:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:48:06 INFO - 'HOME': '/Users/cltbld', 03:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:48:06 INFO - 'LOGNAME': 'cltbld', 03:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:06 INFO - 'MOZ_NO_REMOTE': '1', 03:48:06 INFO - 'NO_EM_RESTART': '1', 03:48:06 INFO - 'PAGER': '/bin/cat', 03:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:06 INFO - 'PWD': '/builds/slave/test', 03:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:48:06 INFO - 'SHELL': '/bin/bash', 03:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:48:06 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:48:06 INFO - 'USER': 'cltbld', 03:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:06 INFO - 'XPC_FLAGS': '0x0', 03:48:06 INFO - 'XPC_SERVICE_NAME': '0', 03:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:07 INFO - Downloading/unpacking jsonschema 03:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:48:09 INFO - Downloading/unpacking functools32 (from jsonschema) 03:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:10 INFO - Downloading functools32-3.2.3-2.tar.gz 03:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:48:10 INFO - warning: no files found matching '*.txt' 03:48:10 INFO - no previously-included directories found matching 'build' 03:48:10 INFO - no previously-included directories found matching 'dist' 03:48:10 INFO - no previously-included directories found matching '.git*' 03:48:10 INFO - Installing collected packages: jsonschema, functools32 03:48:10 INFO - Running setup.py install for functools32 03:48:10 INFO - warning: no files found matching '*.txt' 03:48:10 INFO - no previously-included directories found matching 'build' 03:48:10 INFO - no previously-included directories found matching 'dist' 03:48:10 INFO - no previously-included directories found matching '.git*' 03:48:10 INFO - Successfully installed jsonschema functools32 03:48:10 INFO - Cleaning up... 03:48:10 INFO - Return code: 0 03:48:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:48:10 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')]} 03:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:48:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:10 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')]} 03:48:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:48:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:48:10 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 0x106593a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00058ed30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, '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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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 03:48:10 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 03:48:10 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 03:48:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:48:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:48:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:48:10 INFO - 'HOME': '/Users/cltbld', 03:48:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:48:10 INFO - 'LOGNAME': 'cltbld', 03:48:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:10 INFO - 'MOZ_NO_REMOTE': '1', 03:48:10 INFO - 'NO_EM_RESTART': '1', 03:48:10 INFO - 'PAGER': '/bin/cat', 03:48:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:48:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:10 INFO - 'PWD': '/builds/slave/test', 03:48:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:48:10 INFO - 'SHELL': '/bin/bash', 03:48:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:48:10 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:48:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:48:10 INFO - 'USER': 'cltbld', 03:48:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:48:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:48:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:10 INFO - 'XPC_FLAGS': '0x0', 03:48:10 INFO - 'XPC_SERVICE_NAME': '0', 03:48:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:48:10 INFO - Downloading/unpacking flake8 03:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:48:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:48:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:48:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:48:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:48:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:48:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:48:14 INFO - no previously-included directories found matching 'docs/_build' 03:48:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:48:14 INFO - Running setup.py install for pep8 03:48:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:48:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:48:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:48:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:48:14 INFO - no previously-included directories found matching 'docs/_build' 03:48:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:48:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:48:14 INFO - Cleaning up... 03:48:14 INFO - Return code: 0 03:48:14 INFO - Running post-action listener: _resource_record_post_action 03:48:14 INFO - Running post-action listener: _start_resource_monitoring 03:48:14 INFO - Starting resource monitoring. 03:48:14 INFO - ##### 03:48:14 INFO - ##### Running install step. 03:48:14 INFO - ##### 03:48:14 INFO - Running pre-action listener: _resource_record_pre_action 03:48:14 INFO - Running main action method: install 03:48:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:48:15 INFO - Reading from file tmpfile_stdout 03:48:15 INFO - Detecting whether we're running mozinstall >=1.0... 03:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:48:15 INFO - Reading from file tmpfile_stdout 03:48:15 INFO - Output received: 03:48:15 INFO - Usage: mozinstall [options] installer 03:48:15 INFO - Options: 03:48:15 INFO - -h, --help show this help message and exit 03:48:15 INFO - -d DEST, --destination=DEST 03:48:15 INFO - Directory to install application into. [default: 03:48:15 INFO - "/builds/slave/test"] 03:48:15 INFO - --app=APP Application being installed. [default: firefox] 03:48:15 INFO - mkdir: /builds/slave/test/build/application 03:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:48:48 INFO - Reading from file tmpfile_stdout 03:48:48 INFO - Output received: 03:48:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:48:48 INFO - Running post-action listener: _resource_record_post_action 03:48:48 INFO - ##### 03:48:48 INFO - ##### Running run-tests step. 03:48:48 INFO - ##### 03:48:48 INFO - Running pre-action listener: _resource_record_pre_action 03:48:48 INFO - Running main action method: run_tests 03:48:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:48:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:48:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:48:48 INFO - 2016-04-21 03:48:48.835 screenresolution[1639:7575] starting screenresolution argv=screenresolution get 03:48:48 INFO - 2016-04-21 03:48:48.838 screenresolution[1639:7575] Display 0: 1600x1200x32@60 03:48:48 INFO - 2016-04-21 03:48:48.844 screenresolution[1640:7577] starting screenresolution argv=screenresolution list 03:48:48 INFO - Available Modes on Display 0 03:48:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:48:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:48:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:48:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:48:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:48:49 INFO - Intel Iris: 03:48:49 INFO - Chipset Model: Intel Iris 03:48:49 INFO - Type: GPU 03:48:49 INFO - Bus: Built-In 03:48:49 INFO - VRAM (Dynamic, Max): 1536 MB 03:48:49 INFO - Vendor: Intel (0x8086) 03:48:49 INFO - Device ID: 0x0a2e 03:48:49 INFO - Revision ID: 0x0009 03:48:49 INFO - Displays: 03:48:49 INFO - Display: 03:48:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:48:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:48:49 INFO - Display Serial Number: 1600x1200 60 03:48:49 INFO - Main Display: Yes 03:48:49 INFO - Mirror: Off 03:48:49 INFO - Online: Yes 03:48:49 INFO - Rotation: Supported 03:48:49 INFO - Return code: 0 03:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:48:49 INFO - Python 2.7.3 03:48:49 INFO - Return code: 0 03:48:49 INFO - grabbing minidump binary from tooltool 03:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:49 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 0x106432e00>, '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 0x10625bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10642db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:48:49 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 03:48:49 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 03:48:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:48:49 INFO - Return code: 0 03:48:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:48:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:48:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:48:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:48:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:48:50 INFO - Return code: 0 03:48:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--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 03:48:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0079 --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 03:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51Mx64yTIP/Render', 03:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:48:50 INFO - 'HOME': '/Users/cltbld', 03:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:48:50 INFO - 'LOGNAME': 'cltbld', 03:48:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:48:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:50 INFO - 'MOZ_NO_REMOTE': '1', 03:48:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:48:50 INFO - 'NO_EM_RESTART': '1', 03:48:50 INFO - 'PAGER': '/bin/cat', 03:48:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:50 INFO - 'PWD': '/builds/slave/test', 03:48:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:48:50 INFO - 'SHELL': '/bin/bash', 03:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7AHGZ3aETQ/Listeners', 03:48:50 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 03:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:48:50 INFO - 'USER': 'cltbld', 03:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:50 INFO - 'XPC_FLAGS': '0x0', 03:48:50 INFO - 'XPC_SERVICE_NAME': '0', 03:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:48:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--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 03:48:50 INFO - mozversion application_buildid: 20160421015807 03:48:50 INFO - mozversion application_changeset: 4e3ad95d689a5beabf3c1f41d958794fe00e3767 03:48:50 INFO - mozversion application_display_name: Nightly 03:48:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:48:50 INFO - mozversion application_name: Firefox 03:48:50 INFO - mozversion application_remotingname: firefox 03:48:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:48:50 INFO - mozversion application_vendor: Mozilla 03:48:50 INFO - mozversion application_version: 48.0a1 03:48:50 INFO - mozversion platform_buildid: 20160421015807 03:48:50 INFO - mozversion platform_changeset: 4e3ad95d689a5beabf3c1f41d958794fe00e3767 03:48:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:48:50 INFO - mozversion platform_version: 48.0a1 03:48:50 INFO - using testdate: 1461235730 03:48:50 INFO - actual date: 1461235730 03:48:50 INFO - starting webserver on 'localhost:49277' 03:48:50 INFO - SUITE-START | Running 2 tests 03:48:50 INFO - TEST-START | dromaeo_css 03:48:50 INFO - operating with platform_type : mac_ 03:48:50 INFO - Initialising browser for dromaeo_css test... 03:48:50 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpMmjKBS/profile http://localhost:49277/getInfo.html) 03:48:52 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 03:48:52 INFO - PROCESS | 1658 | colorDepth:24 03:48:52 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 03:48:52 INFO - PROCESS | 1658 | __metrics 03:48:53 INFO - PROCESS | 1658 | 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" 03:48:53 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:48:53 INFO - TEST-INFO | 1658: exit 0 03:48:53 INFO - Browser initialized. 03:48:53 INFO - Running cycle 1/1 for dromaeo_css test... 03:48:53 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpMmjKBS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:49:05 INFO - PROCESS | 1660 | RSS: Main: 237776896 03:49:05 INFO - PROCESS | 1660 | 03:49:57 INFO - PROCESS | 1660 | 1461235797362 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] 03:52:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 03:52:09 INFO - PROCESS | 1660 | RSS: Main: 333901824 03:52:09 INFO - PROCESS | 1660 | 03:55:11 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 03:55:12 INFO - PROCESS | 1660 | RSS: Main: 378687488 03:55:12 INFO - PROCESS | 1660 | 03:58:14 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 03:58:15 INFO - PROCESS | 1660 | RSS: Main: 377393152 03:58:15 INFO - PROCESS | 1660 | 04:01:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 04:01:18 INFO - PROCESS | 1660 | RSS: Main: 377098240 04:01:18 INFO - PROCESS | 1660 | 04:04:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 04:04:21 INFO - PROCESS | 1660 | RSS: Main: 350552064 04:04:21 INFO - PROCESS | 1660 | 04:07:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 04:07:25 INFO - PROCESS | 1660 | RSS: Main: 380624896 04:07:25 INFO - PROCESS | 1660 | 04:07:25 INFO - PROCESS | 1660 | __start_tp_report 04:07:25 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 04:07:25 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 04:07:25 INFO - PROCESS | 1660 | |i|pagename|runs| 04:07:25 INFO - PROCESS | 1660 | |0;dojo.html;3417.2652879630878;3457.757956943015;3509.4560343146823;3509.5613048368964;3533.2403533240363;5614.185943038257;5695.259616249882;5700.201971723956;5713.45722156395;5718.485336319731;5708.914366284509;5737.426257374261;5760.596758226926;5859.296884373881;5925.377835327293;5159.174532074869;5191.766370513336;5208.8437346879655;5223.425423203445;5320.787168513255;5285.652158699526;5299.735013249341;5325.946740532584;5357.544608708265;5363.141897296433;4263.424437700902;4688.085823264465;4895.55939965403;5174.353205849269;5231.869203269911;4278.6363159131515;5240.292560504359;5253.9737301313635;5260.789568417259;5283.471652834724;5118.2578526113875;5202.557782588485;5229.816956406507;5242.816501422432;5245.86885327866;5591.580631452616;5620.662760234399;5660.632058916157;5699.914501282484;5711.7144142792695;6241.251049873998;6248.875202463555;6289.496840252768;6290.779822706183;6308.589941653778;6135.9079613805825;6254.155688981975;6280;6328.936710632881;6335.588186767845;5749.425057494259;5794.478496935211;5838.8540286492325;5843.444872737083;5859.882802343972;6281.403266689657;6287.371262873768;6290.779822706275;6303.432691057827;6309.684515774193;6110.358412366726;6113.663748493876;6120.14317995481;6123.173371594867;6128.2033335666965;6099.69501524922;6185.474234690012;6246.281677607042;6265.404786545271;6290.811275661737;6241.095041219089;6245.375462453809;6247.312795592481;6248.062790581449;6278.058291256257;6231.501479881598;6283.717232724538;6284;6291.748330066746;6303.117563541108;4829.203181475017;4927.249667954925;4944.480829512921;4949.826756063592;4949.876253093629;4393.29707246839;4458.665600080007;4502.16709908668;4509.594136527729;4514.593686568159;5701.657900525982;5710.857228569236;5716.256886604715;5717.285339332583;5725.1698503716725;6146.600470969463;6175.59858609189;6205.844853878689;6226.066090086523;6277.686115694197;5670.206171136044;5673.120666296731;5681.318241811009;5699.230603868507;5730.025895597759;5322.494363035506;5356.491133342326;5377.059014672417;5385.596080293915;5393.8112166074;6320.083587879732;6412.487001039905;6415.037744338293;6429.22849258092;6431.517636177306;6199.318075011835;6236.03341482071;6250.249970003629;6253.155823963707;6265.154204182377;5320.813771517978;6170.660613666291;6316.210473690789;6401.775937842153;6544.4764418845425;6065.150878877049;6192.159846439231;6220.813375598739;6247.406496382836;6249.750009999549;6279.905801413074;6289.433950944437;6303.023031430135;6313.2424109107205;6341;4571.862844114682;5762.510186634016;5964.135200396005;6087.665178415053;6128.142060111677;5998;6098.32918378969;6128.8774224516155;6151.138840562415;6158.230221222348;5950.018246989198;5971.402859713994;5984.640921544721;5995.850103747442;6021.42796434338;5935.436133567304;6006.819795406145;6099.4815440688035;6131.437835141539;6139.0321984548755;6055.364186760326;6057.969710151421;6065.848353791014;6067.484263837623;6086.056661217518 04:07:25 INFO - PROCESS | 1660 | |1;ext.html;526.8103482746211;530.4562823106315;584.617075815341;613.8618810767575;726.8443175351191;29838.254043648918;30570;37410;38602.648907288254;38673.80663096681;39540.406893896616;40026.199476010464;40199;40321;40336.59663403365;9475.905240947588;9742.318037737361;9749.366291191085;9749.658761943334;9753.463559504224;9525.618975240983;9635.470049147294;9638.469884156368;9647.276454265922;9650.08324209199;9625.085616866387;9685.515724213761;9687.903120968806;9714.319997600136;9736.123748862601;9619.134277914985;9644.421334719938;9649.276304277208;9650.179734722487;9668.70993870185;9417.340786144972;9543.33196676233;9544.761380965461;9549;9555.569999350046;39339.01652458712;39921.40117898234;39945.400818987735;39948.80025599882;39955.60044399548;3149.992002559182;3194.4090343286566;3208.449581506581;3610.3862343401684;3988.521377434726;28172.718272817216;28426.28934276639;28467.57298640522;29132.27169320763;29171.708282917323;2684.396010897552;2713.986430067857;2714.4571085782923;2784.665840099201;2791.21845883151;450925.7453712743;452983;454360.45639543515;454834;457007;35297.29405411929;36397.45403818945;36452;59983.700081499315;60304.698476508216;34677.13307167341;34850;34896.65103348985;34958.47562286568;34980.65019349825;14220.075695079993;14435.473792086343;14443;14459.349329280207;14466.638334041732;12072.15494915365;12142.271463712206;12165.878341216652;12183;12190.756184876253;8121.269085782308;8163.918360816435;8186.877196842052;8230.670773169126;8265.421420500628;2860.2420358604986;3377.8142202178624;3390.4066788311948;3399.5070714746225;3422.178677117474;826.962786674601;998.1266391907081;1053.273241463403;1094.6153116307878;1197.3115458611333;29111.85444072766;29235.56146657802;29399.559006614916;29925.551116733266;30048.098557043322;1060.1148041385375;1066.195022757813;1108.51225460797;1109.9223054386116;1112.093643680398;11817.468213930393;15841.287142078634;15867.444639437565;15983.408364617244;16185.271662775203;511828;520943;522114;522404;523892;53977.46022539724;56653.43346566564;56688.866222675315;56692;56925.71537142288;1335.3990704182963;1375.40170026039;1376.5526203983745;1482.970340593182;1484.6733718581895;23231;26154;29376.41247175087;30137.397252055274;30503;50569.494305056476;55598.16602751043;57084.71457642685;57271.42728572828;57292.71353643205;539661;541221;548188;549124;549884;33872.830635846665;34784.82607586946;41782.16435671331;42545.361819572114;42559.57440425556;42094.57905420907;42158.1568368637;42526;42799.1440171201;43676.781616091714;34358;35316.64683353133;35935.82032089823;36114.27771444609;36254.637453625124;34564.48153277753;35050.474242886885;35765.64234357623;35975.280494389444;35975.640243598275;32046.03861884147;32612.673873261912;32626.51060234146;32813.83593082019;36035.459468107474;23991.640125397786;24771.87614061918;30977.690223098383;30982.22544436317;31039;19264.61470770605;23054.962526686002;23632.645510317703;24090.02762526822;24373.3906652328 04:07:25 INFO - PROCESS | 1660 | |2;jquery.html;392.2233976726082;393.13118009199667;393.26852055177375;395.81594558530276;405.8863518214901;19842.40472785817;20790.168393264288;20858.478538036557;20871.373858784224;21410.785892141073;17663.82336176638;19346.613067738635;20485.38543843687;21289.893550532226;21296.148154073817;5379.919301210475;5932.614380065282;6105.664188469633;6106.572539922207;6107.450329470346;6014.308354539218;6030.728617212236;6091.329953705089;6105.664188469633;6114.816555503342;5217.895642087156;5270.5520030797425;5322.361316642008;5325.7869685212545;5329.573634109282;5291.259223708684;5301.284326615915;5302.549283310904;5320.733963301839;6022.548308876808;5167.509086636764;5227.869303267411;5266.657667251617;5267.604929630254;5269;13127.277999710011;14060.367283472267;14062.156270623693;14081.788773168411;26433.603495947576;2146.3346362627635;2153.192552792703;2490.306591517767;2596.7273436289297;2825.350169461015;9528.428294302295;9834.557444914924;10244.846327305097;10248.180145588334;10282.7429314267;1448.2686243446992;1701.5235733994502;1716.4164184177314;1746.56335916021;1930.04462790918;122409;127681.99999999907;127695;128620;130380;11505.884941150649;11633.243839150597;11646.24299420535;11675.649730508097;11844.230125041844;15263.618409539851;15303.846961530466;15322.770158447633;15335.61660958485;15348.846511534743;8586.613602387908;8599;8630.827383452295;8635.654573817103;8759.12408759119;6846.828829279209;8379.664813407395;8486.787830304169;8492.532910689848;8506.532140732195;3910.8463003414063;4546.704464209815;4634;4646.8141274348645;4711.434627844612;1770.7166853303668;1877.6620208362353;1899.9075531569404;1948.1038722187668;1962.391658585843;868.600443795861;879.8020445399862;898.6789419553246;918.912703293186;979.8003482182546;18871;19989.500262493555;20311.492212694506;20969.370918872457;21232.36302910915;926.2636204217663;936.9063093690713;942.1379437814323;973.6786860336072;980.5930538826307;10859.891401086046;11168.329900206014;11183.161262905152;11191.160662950311;11198.776024479628;598610;606802.9659851673;619130;628532;630747;119939;129726;132531;135579;137372;1008.2690049714046;1011.327467234281;1030.860833787444;1032.0608246495653;1032.7779527401644;16648.50163485292;18325;18674.533136671147;18829.623407531497;19048.04759762034;41244;51360;51611;53060.469395305554;53101.7344913273;65457;73090.26909730835;76829;77858;78659;7076.787696369117;7680.385569154566;8132.796680083045;8192.139825318256;8515.105913878953;10751.354918704903;12382.546657499619;12400.721750995892;12503.687407814878;12829.166104203197;10717.839232411365;10796.082333001468;10802.67591972242;10950.94524527404;11008.669739907815;9483.861936567657;9693.466859322805;10389.220808439548;10544.736381590521;10947.945260273647;10162.187025038127;10938.070264027328;10970.177236707119;10975.890241097806;11382.54469821198;8076.959615202121;8166.510009399218;8212.548309843016;8221.712240071569;8483.660653573788;10563.788724225626;10978.560857565928;11108.222424430212;11224;11230.157738169506 04:07:25 INFO - PROCESS | 1660 | |3;mootools.html;1411.4918629293456;1414.4625042483854;1591.649837035852;1814.3559036542026;1995.2118913029344;7870.331021863141;8582.527960962145;8806.471611703302;8815.647374105029;8818.250448711851;8450.577471126435;8702.477851328924;8704.390692651517;8711.86932196016;8715.997660269071;1927.575933294673;2245.9775402246014;2347.58917189492;2419.794317483016;2446.522928029035;2462.335169504233;2489.4025433895918;2489.7136829264687;2496.3883848457062;2498.475320182764;2429.186222615417;2497.2383423055962;2497.7502249775057;2500.1874390822964;2502.887370068351;2149.7742737012622;2400.699912510936;2455.312512496504;2475.851448913071;2477.8513289202524;2447.9143229986867;2456.7051953765485;2480.838745481538;2487.825852190347;2488.2783992642294;8132.918670813276;8223.972003499563;8230.83538329236;8237.217464340938;8241.546714930677;5119.10415677255;5347;5353.437889021675;5367.1680889462195;5382.138857782775;3293.8517766700556;3427.4516077427493;3489.6335884731993;3490.371733088043;3492.1269682579355;963.7831487915233;986.2603047714215;997.551102004101;999.2555546118116;999.9300048996573;11470;11617.825732614017;11624.476898539502;11625.186236963416;11626.651200463999;4493.483249426358;4517.277235642281;4614.76926153691;4663.650226232979;4697.553732395417;9110.49892255919;9121.45271283725;9124.726258212264;9136.6802161924;9140.131687489678;5228.869278268074;5238.109521381374;5246.554042906396;5274.841754747364;5278.419373868871;4375.2780791169735;4546.40896683429;4784.736839473792;4917.9918116785975;4937.950620493749;3320.8543052646796;3660.286244182359;3672.8163591820303;3673.7428380013143;3790.639889210521;1214.5384753793503;1222.9571964981183;1226.5707002549036;1236.5981056156604;1252.511520507003;742.4913933955258;842.6839935024366;855.2730179347504;869.4392117084445;880.564120760215;4976.328195693607;5021.84934451967;5507.9449205507435;5727.742251598688;5795.913061304083;450.1739308369114;454.8317122664569;459.5358687725355;465.38569088802456;473.14597152140715;3079.6612372638992;3584.390653588896;3701.550098651669;3783.5270591176104;3795.0512371907025;8907.376483646083;10623.946880265703;10713.571457141783;10838.945805270923;10877.292975956538;10641.627543035956;10649.520771565298;10750.78498430027;10791.352518848737;10831.783364332827;8123.3095186908595;8139.186081391814;8223.753287401269;8247.546384948886;8248.711295104762;5198.402183748917;5359.713668719557;5410.161424979135;5664.546836253007;5695.202671625892;9404;9723.805523889625;9827.459489727848;9839.40963542161;9894.45580493051;7649.235076492307;9884.604615815288;9947.502624868872;10020.298579099393;10035.49822508857;6815.318468153145;7043.7534686285735;7232.63836808147;7248.275172482709;7384.409247260313;7167.892481612885;8060.31487323584;8139.430239883388;8159.26566609008;8233.135520770233;6290.339514350927;7222.855542889218;7298.4891057625455;7347.70609175627;7453.440991925519;7154.320339567733;7170.96414517924;7229.204787473269;7261.213406536794;7305.707771689073;6707.932920670731;6847.281035491401;6850.45196384298;6853.0405743195;6871.690773915086;4715.599174070242;4780.067886762048;4805.615550756001;4868.123737727243;5012.348394708664;4776.474587795409;4784.593309568726;4840.2255639097575;4846.927296090491;4920.704757714549 04:07:25 INFO - PROCESS | 1660 | |4;prototype.html;274.6319931292069;305.42579949694573;313.1466753097809;325.78009843355727;326.8398484742477;5987.131865879445;6007.819765407039;6020.367861374559;6022.698865056752;6069.241344831896;5605.915911261324;5732.254806875111;5840.591158618899;5843.620164689303;5956.51543846849;3702.2040261343805;4140.441040459537;4515.729056256635;4535.773211339436;4569.246074397721;4049.4330793688905;4130.459767243139;4505.301678239878;4512.142692888341;4527.822766080826;4376.627986621141;4426.29179331307;4513.616342610882;4517.525659805722;4531.977340113295;3704.814759262026;4448.644108471314;4489.349044388562;4502.932456013163;4511.894585826493;3874.2832575973534;3879.952412848543;3883.417487376888;3893.961060389388;3921.901952451183;5094.745262736886;5322.840314790524;5332.066888294533;5434.239206511092;5687.677365202435;4682.461516925563;4877.975625118729;5045.571126454256;5097.082525145473;5105.540501354859;6296.716647750816;6307.905381419282;6311.810645680637;6323.30443651198;6336.2396512418345;3456;4005.439238506611;4158.981049642826;4171.165766846643;4189.371594260855;5414.052540805344;6426.646534440604;6471.320511346288;6546.93453065468;6581.30896255891;4211.599898009684;4218.873433796991;4219.873403797891;4226.344916537942;4253.680973926906;3571.678548930609;4265.701400901907;4298.548652391454;4298.591633794847;4313.956860431355;4618.237990731559;5015.297858299841;5037.002983224976;5062.367204099488;5064.417591976896;5727.11229759388;5988.580799344091;6141.539384546177;6364.936350636434;6377.915754321776;1320.6038188543398;1374.5257886029303;1394.2540740703676;1460.1239256446047;1490.299559207171;939.6523286384082;951.219999600335;956.8756061712072;958.2812890332251;964.5996911281879;5801.564882633819;5805.187273781674;5818.51973980033;5820.621659592113;5850.678212698343;6412.198475190601;6414.390632889868;6432.324605916311;6453.741850326029;6459.257185423737;5153.845384638392;5281.551068159172;5688.800891968761;5759.65442073477;5795.507381872588;2894.9276268093045;3291.308825146746;3357.127146941764;3399.6600339965808;3413.2661477782394;6339.334369891188;6379.808605741835;6623.7350505980185;6650.068990341356;6710.463162946952;6523.575967562188;6569.57297775643;6570.704318305592;6583.604983700993;6587.736490540325;6425.807225783234;6651.1423374443675;6669.099671544376;6677.599344039276;6678.432333251728;5391.786847959178;6353.809385718436;6406.807795766134;6415.518836087405;6435.581687190318;6250.4687101595055;6277.30949595535;6294.685265736786;6345.587536810092;6352.841178970562;5780.826575202751;6384.585001974857;6650.800475985729;6663.433608143168;6705.6647167642395;4159.958400416078;4193.370994350872;4196.5588217661225;4207.600277973587;4215.388768628593;6100.115483254992;6383.39357761012;6394.488440924808;6402.90395644075;6414.2855001574735;5032.547070763649;5809.50619197373;6286.9371306288185;6351.2060992375955;6401.87196256063;5287.021900948337;6353.205849268841;6363.522735794926;6365.64988925595;6382.776602818879;5896.587238893408;6157.9692101540995;6159.260888693386;6168.537359697951;6198.706478602946;6331.176946996861;6331.303556608677;6386.584871983307;6387.776427825004;6395.744170233324;6009.055562774121;6233.812985610439;6250.4374606285655;6269.216347956505;6269.279032911274 04:07:25 INFO - PROCESS | 1660 | |5;yui.html;663.1445435388348;692.8648913461875;739.9926000739998;769.253823790923;807.1283014344508;14766;14819.036762610436;14994.625134371647;15010.549683509498;15011.624709382271;13475.393607287671;14343.856561434382;14401.92799036006;14416.062955907884;14470.855291447082;728.3590440412456;761.3338328962158;911.3392790227066;911.8130783189447;923.1783712495851;941.5857022909931;944.1738478831023;947.2422062350126;947.2611363136764;949.7150854743584;989.3915242126077;994.2741798487109;995.1988649137442;995.4326034160531;996.0537489385077;754.4944886925718;945.2721404518483;988.0465350936345;989.6140505202976;997.5112195024458;738.8337624034602;740.2153717059933;740.9814754631124;742.9108506979143;743.0711610486892;14981.925090374478;15001.32494037771;15003.849961500464;15004.324805383783;15033.398664053535;12282.877171228263;12451.501939922393;12474.43865026076;12678.492860285578;12792.552260670831;7549.849003019909;9123.133302336268;9138.497382644018;9151.450912945245;9162.908370916271;3408.590969083701;3471.3230919970615;3476.391631464509;3494.475828625701;3662.249238906018;75565.48869022589;76239.6188019062;77116.22883771146;78326.60836695837;79886.60056699737;9397.248220142372;9438.858417123749;9440.763980900396;9492.003339649238;9517;21441.249556265455;21707.457313567287;21710.674339884914;21724.23965161212;21730;7642.4650274480255;7677.155512893577;7711.48099879586;7935.761927142195;8025.07711613172;4068.389741538793;4113.321301985199;4170.874873753793;4239.576042395735;4411.911761764747;3561.269939662363;3659.2315613720884;3661.761985470936;3664.8350824212976;3671.9816400917825;549.4862303746011;635.9682015899186;659.535027805396;662.5130529061137;688.6281408039703;502.4397796456924;512.4900723779863;514.5883293365292;536.3376230355508;570.1789423230534;7587.810304742426;7643.7324693636565;7676.385889128856;7751.651175697107;7931.246531579491;547.622140722908;552.3068548971015;557.7211394302849;558.4778232352763;559.0803128853095;3613.385724426854;3626.7642603230706;3646.4894914711963;3681.4661874028116;3897.7271590988357;92172.07827921635;93787;94292;94406;94603.0539694608;74392;75082.6245868778;75206.62396688091;75604.62197688976;76309.61845190739;65811;66286.00570991535;67426;67684.32315676879;67773;5843.853903652443;7238.203797582157;7326.3406293433845;7499.362554182955;7612.3910087194;41850.37224441575;43057.354139688556;43387;43568.782156089015;43715.78142109396;62396.37603624087;63993.3600664006;64194.07417777474;64460.677696611216;64585.677071614344;9890;9890.159336456481;9948.303618746619;10094.798104038025;10268;10342.689719308433;10368.948155259175;10632.362058276529;10710;11062.170337224901;9918.504074796376;9978.650747223812;10234.232432567438;10237.795244094927;10261;9847.867495237853;10154.086132248132;10156.746081348025;10182.847257290998;10244.64143754965;9745.415275083518;10062.245331600014;10070.949645251727;10071.244656650633;10172.338797978107;8340.541270229953;8412.284955778827;8509.276711479593;8630.78423039404;8712.998005229227;8439.240468357877;8480.575971201291;8742.956285218534;9207.17135457812;9320.95339523298 04:07:25 INFO - PROCESS | 1660 | __end_tp_report 04:07:25 INFO - PROCESS | 1660 | __start_cc_report 04:07:25 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1197 04:07:25 INFO - PROCESS | 1660 | __end_cc_report 04:07:25 INFO - PROCESS | 1660 | __startTimestamp1461236845128__endTimestamp 04:07:25 INFO - PROCESS | 1660 | 04:07:25 INFO - PROCESS | 1660 | ------- Summary: start ------- 04:07:25 INFO - PROCESS | 1660 | Number of tests: 6 04:07:25 INFO - PROCESS | 1660 | 04:07:25 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5739.74 Median:5984.64 stddev:656.80 (11.0%) stddev-sans-first:633.05 04:07:25 INFO - PROCESS | 1660 | Values: 3417.3 3457.8 3509.5 3509.6 3533.2 5614.2 5695.3 5700.2 5713.5 5718.5 5708.9 5737.4 5760.6 5859.3 5925.4 5159.2 5191.8 5208.8 5223.4 5320.8 5285.7 5299.7 5325.9 5357.5 5363.1 4263.4 4688.1 4895.6 5174.4 5231.9 4278.6 5240.3 5254.0 5260.8 5283.5 5118.3 5202.6 5229.8 5242.8 5245.9 5591.6 5620.7 5660.6 5699.9 5711.7 6241.3 6248.9 6289.5 6290.8 6308.6 6135.9 6254.2 6280.0 6328.9 6335.6 5749.4 5794.5 5838.9 5843.4 5859.9 6281.4 6287.4 6290.8 6303.4 6309.7 6110.4 6113.7 6120.1 6123.2 6128.2 6099.7 6185.5 6246.3 6265.4 6290.8 6241.1 6245.4 6247.3 6248.1 6278.1 6231.5 6283.7 6284.0 6291.7 6303.1 4829.2 4927.2 4944.5 4949.8 4949.9 4393.3 4458.7 4502.2 4509.6 4514.6 5701.7 5710.9 5716.3 5717.3 5725.2 6146.6 6175.6 6205.8 6226.1 6277.7 5670.2 5673.1 5681.3 5699.2 5730.0 5322.5 5356.5 5377.1 5385.6 5393.8 6320.1 6412.5 6415.0 6429.2 6431.5 6199.3 6236.0 6250.2 6253.2 6265.2 5320.8 6170.7 6316.2 6401.8 6544.5 6065.2 6192.2 6220.8 6247.4 6249.8 6279.9 6289.4 6303.0 6313.2 6341.0 4571.9 5762.5 5964.1 6087.7 6128.1 5998.0 6098.3 6128.9 6151.1 6158.2 5950.0 5971.4 5984.6 5995.9 6021.4 5935.4 6006.8 6099.5 6131.4 6139.0 6055.4 6058.0 6065.8 6067.5 6086.1 04:07:25 INFO - PROCESS | 1660 | 04:07:25 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:62837.34 Median:36184.46 stddev:135621.19 (374.8%) stddev-sans-first:135920.95 04:07:25 INFO - PROCESS | 1660 | Values: 526.8 530.5 584.6 613.9 726.8 29838.3 30570.0 37410.0 38602.6 38673.8 39540.4 40026.2 40199.0 40321.0 40336.6 9475.9 9742.3 9749.4 9749.7 9753.5 9525.6 9635.5 9638.5 9647.3 9650.1 9625.1 9685.5 9687.9 9714.3 9736.1 9619.1 9644.4 9649.3 9650.2 9668.7 9417.3 9543.3 9544.8 9549.0 9555.6 39339.0 39921.4 39945.4 39948.8 39955.6 3150.0 3194.4 3208.4 3610.4 3988.5 28172.7 28426.3 28467.6 29132.3 29171.7 2684.4 2714.0 2714.5 2784.7 2791.2 450925.7 452983.0 454360.5 454834.0 457007.0 35297.3 36397.5 36452.0 59983.7 60304.7 34677.1 34850.0 34896.7 34958.5 34980.7 14220.1 14435.5 14443.0 14459.3 14466.6 12072.2 12142.3 12165.9 12183.0 12190.8 8121.3 8163.9 8186.9 8230.7 8265.4 2860.2 3377.8 3390.4 3399.5 3422.2 827.0 998.1 1053.3 1094.6 1197.3 29111.9 29235.6 29399.6 29925.6 30048.1 1060.1 1066.2 1108.5 1109.9 1112.1 11817.5 15841.3 15867.4 15983.4 16185.3 511828.0 520943.0 522114.0 522404.0 523892.0 53977.5 56653.4 56688.9 56692.0 56925.7 1335.4 1375.4 1376.6 1483.0 1484.7 23231.0 26154.0 29376.4 30137.4 30503.0 50569.5 55598.2 57084.7 57271.4 57292.7 539661.0 541221.0 548188.0 549124.0 549884.0 33872.8 34784.8 41782.2 42545.4 42559.6 42094.6 42158.2 42526.0 42799.1 43676.8 34358.0 35316.6 35935.8 36114.3 36254.6 34564.5 35050.5 35765.6 35975.3 35975.6 32046.0 32612.7 32626.5 32813.8 36035.5 23991.6 24771.9 30977.7 30982.2 31039.0 19264.6 23055.0 23632.6 24090.0 24373.4 04:07:25 INFO - PROCESS | 1660 | 04:07:25 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:35369.67 Median:2149.76 stddev:103105.08 (4796.1%) stddev-sans-first:103360.86 04:07:25 INFO - PROCESS | 1660 | Values: 392.2 393.1 393.3 395.8 405.9 19842.4 20790.2 20858.5 20871.4 21410.8 17663.8 19346.6 20485.4 21289.9 21296.1 5379.9 5932.6 6105.7 6106.6 6107.5 6014.3 6030.7 6091.3 6105.7 6114.8 5217.9 5270.6 5322.4 5325.8 5329.6 5291.3 5301.3 5302.5 5320.7 6022.5 5167.5 5227.9 5266.7 5267.6 5269.0 13127.3 14060.4 14062.2 14081.8 26433.6 2146.3 2153.2 2490.3 2596.7 2825.4 9528.4 9834.6 10244.8 10248.2 10282.7 1448.3 1701.5 1716.4 1746.6 1930.0 122409.0 127682.0 127695.0 128620.0 130380.0 11505.9 11633.2 11646.2 11675.6 11844.2 15263.6 15303.8 15322.8 15335.6 15348.8 8586.6 8599.0 8630.8 8635.7 8759.1 6846.8 8379.7 8486.8 8492.5 8506.5 3910.8 4546.7 4634.0 4646.8 4711.4 1770.7 1877.7 1899.9 1948.1 1962.4 868.6 879.8 898.7 918.9 979.8 18871.0 19989.5 20311.5 20969.4 21232.4 926.3 936.9 942.1 973.7 980.6 10859.9 11168.3 11183.2 11191.2 11198.8 598610.0 606803.0 619130.0 628532.0 630747.0 119939.0 129726.0 132531.0 135579.0 137372.0 1008.3 1011.3 1030.9 1032.1 1032.8 16648.5 18325.0 18674.5 18829.6 19048.0 41244.0 51360.0 51611.0 53060.5 53101.7 65457.0 73090.3 76829.0 77858.0 78659.0 7076.8 7680.4 8132.8 8192.1 8515.1 10751.4 12382.5 12400.7 12503.7 12829.2 10717.8 10796.1 10802.7 10950.9 11008.7 9483.9 9693.5 10389.2 10544.7 10947.9 10162.2 10938.1 10970.2 10975.9 11382.5 8077.0 8166.5 8212.5 8221.7 8483.7 10563.8 10978.6 11108.2 11224.0 11230.2 04:07:25 INFO - PROCESS | 1660 | 04:07:25 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5537.60 Median:4994.34 stddev:3126.49 (62.6%) stddev-sans-first:3119.88 04:07:25 INFO - PROCESS | 1660 | Values: 1411.5 1414.5 1591.6 1814.4 1995.2 7870.3 8582.5 8806.5 8815.6 8818.3 8450.6 8702.5 8704.4 8711.9 8716.0 1927.6 2246.0 2347.6 2419.8 2446.5 2462.3 2489.4 2489.7 2496.4 2498.5 2429.2 2497.2 2497.8 2500.2 2502.9 2149.8 2400.7 2455.3 2475.9 2477.9 2447.9 2456.7 2480.8 2487.8 2488.3 8132.9 8224.0 8230.8 8237.2 8241.5 5119.1 5347.0 5353.4 5367.2 5382.1 3293.9 3427.5 3489.6 3490.4 3492.1 963.8 986.3 997.6 999.3 999.9 11470.0 11617.8 11624.5 11625.2 11626.7 4493.5 4517.3 4614.8 4663.7 4697.6 9110.5 9121.5 9124.7 9136.7 9140.1 5228.9 5238.1 5246.6 5274.8 5278.4 4375.3 4546.4 4784.7 4918.0 4938.0 3320.9 3660.3 3672.8 3673.7 3790.6 1214.5 1223.0 1226.6 1236.6 1252.5 742.5 842.7 855.3 869.4 880.6 4976.3 5021.8 5507.9 5727.7 5795.9 450.2 454.8 459.5 465.4 473.1 3079.7 3584.4 3701.6 3783.5 3795.1 8907.4 10623.9 10713.6 10838.9 10877.3 10641.6 10649.5 10750.8 10791.4 10831.8 8123.3 8139.2 8223.8 8247.5 8248.7 5198.4 5359.7 5410.2 5664.5 5695.2 9404.0 9723.8 9827.5 9839.4 9894.5 7649.2 9884.6 9947.5 10020.3 10035.5 6815.3 7043.8 7232.6 7248.3 7384.4 7167.9 8060.3 8139.4 8159.3 8233.1 6290.3 7222.9 7298.5 7347.7 7453.4 7154.3 7171.0 7229.2 7261.2 7305.7 6707.9 6847.3 6850.5 6853.0 6871.7 4715.6 4780.1 4805.6 4868.1 5012.3 4776.5 4784.6 4840.2 4846.9 4920.7 04:07:25 INFO - PROCESS | 1660 | 04:07:25 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5091.47 Median:5814.01 stddev:1605.76 (27.6%) stddev-sans-first:1569.04 04:07:25 INFO - PROCESS | 1660 | Values: 274.6 305.4 313.1 325.8 326.8 5987.1 6007.8 6020.4 6022.7 6069.2 5605.9 5732.3 5840.6 5843.6 5956.5 3702.2 4140.4 4515.7 4535.8 4569.2 4049.4 4130.5 4505.3 4512.1 4527.8 4376.6 4426.3 4513.6 4517.5 4532.0 3704.8 4448.6 4489.3 4502.9 4511.9 3874.3 3880.0 3883.4 3894.0 3921.9 5094.7 5322.8 5332.1 5434.2 5687.7 4682.5 4878.0 5045.6 5097.1 5105.5 6296.7 6307.9 6311.8 6323.3 6336.2 3456.0 4005.4 4159.0 4171.2 4189.4 5414.1 6426.6 6471.3 6546.9 6581.3 4211.6 4218.9 4219.9 4226.3 4253.7 3571.7 4265.7 4298.5 4298.6 4314.0 4618.2 5015.3 5037.0 5062.4 5064.4 5727.1 5988.6 6141.5 6364.9 6377.9 1320.6 1374.5 1394.3 1460.1 1490.3 939.7 951.2 956.9 958.3 964.6 5801.6 5805.2 5818.5 5820.6 5850.7 6412.2 6414.4 6432.3 6453.7 6459.3 5153.8 5281.6 5688.8 5759.7 5795.5 2894.9 3291.3 3357.1 3399.7 3413.3 6339.3 6379.8 6623.7 6650.1 6710.5 6523.6 6569.6 6570.7 6583.6 6587.7 6425.8 6651.1 6669.1 6677.6 6678.4 5391.8 6353.8 6406.8 6415.5 6435.6 6250.5 6277.3 6294.7 6345.6 6352.8 5780.8 6384.6 6650.8 6663.4 6705.7 4160.0 4193.4 4196.6 4207.6 4215.4 6100.1 6383.4 6394.5 6402.9 6414.3 5032.5 5809.5 6286.9 6351.2 6401.9 5287.0 6353.2 6363.5 6365.6 6382.8 5896.6 6158.0 6159.3 6168.5 6198.7 6331.2 6331.3 6386.6 6387.8 6395.7 6009.1 6233.8 6250.4 6269.2 6269.3 04:07:25 INFO - PROCESS | 1660 | 04:07:25 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:17503.14 Median:34044.57 stddev:24921.18 (73.2%) stddev-sans-first:24959.01 04:07:25 INFO - PROCESS | 1660 | Values: 663.1 692.9 740.0 769.3 807.1 14766.0 14819.0 14994.6 15010.5 15011.6 13475.4 14343.9 14401.9 14416.1 14470.9 728.4 761.3 911.3 911.8 923.2 941.6 944.2 947.2 947.3 949.7 989.4 994.3 995.2 995.4 996.1 754.5 945.3 988.0 989.6 997.5 738.8 740.2 741.0 742.9 743.1 14981.9 15001.3 15003.8 15004.3 15033.4 12282.9 12451.5 12474.4 12678.5 12792.6 7549.8 9123.1 9138.5 9151.5 9162.9 3408.6 3471.3 3476.4 3494.5 3662.2 75565.5 76239.6 77116.2 78326.6 79886.6 9397.2 9438.9 9440.8 9492.0 9517.0 21441.2 21707.5 21710.7 21724.2 21730.0 7642.5 7677.2 7711.5 7935.8 8025.1 4068.4 4113.3 4170.9 4239.6 4411.9 3561.3 3659.2 3661.8 3664.8 3672.0 549.5 636.0 659.5 662.5 688.6 502.4 512.5 514.6 536.3 570.2 7587.8 7643.7 7676.4 7751.7 7931.2 547.6 552.3 557.7 558.5 559.1 3613.4 3626.8 3646.5 3681.5 3897.7 92172.1 93787.0 94292.0 94406.0 94603.1 74392.0 75082.6 75206.6 75604.6 76309.6 65811.0 66286.0 67426.0 67684.3 67773.0 5843.9 7238.2 7326.3 7499.4 7612.4 41850.4 43057.4 43387.0 43568.8 43715.8 62396.4 63993.4 64194.1 64460.7 64585.7 9890.0 9890.2 9948.3 10094.8 10268.0 10342.7 10368.9 10632.4 10710.0 11062.2 9918.5 9978.7 10234.2 10237.8 10261.0 9847.9 10154.1 10156.7 10182.8 10244.6 9745.4 10062.2 10070.9 10071.2 10172.3 8340.5 8412.3 8509.3 8630.8 8713.0 8439.2 8480.6 8743.0 9207.2 9321.0 04:07:25 INFO - PROCESS | 1660 | -------- Summary: end -------- 04:07:25 INFO - PROCESS | 1660 | 04:07:25 INFO - TEST-INFO | 1660: exit 0 04:07:25 INFO - TEST-OK | dromaeo_css | took 1115124ms 04:07:25 INFO - TEST-START | kraken 04:07:25 INFO - operating with platform_type : mac_ 04:07:25 INFO - Initialising browser for kraken test... 04:07:25 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpOIs5v7/profile http://localhost:49277/getInfo.html) 04:07:27 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 04:07:27 INFO - PROCESS | 1674 | colorDepth:24 04:07:27 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 04:07:27 INFO - PROCESS | 1674 | __metrics 04:07:27 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:07:27 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:07:28 INFO - TEST-INFO | 1674: exit 0 04:07:28 INFO - Browser initialized. 04:07:28 INFO - Running cycle 1/1 for kraken test... 04:07:28 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpOIs5v7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:07:40 INFO - PROCESS | 1676 | RSS: Main: 237821952 04:07:40 INFO - PROCESS | 1676 | 04:07:43 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 04:07:43 INFO - PROCESS | 1676 | RSS: Main: 286375936 04:07:43 INFO - PROCESS | 1676 | 04:07:46 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 04:07:47 INFO - PROCESS | 1676 | RSS: Main: 298614784 04:07:47 INFO - PROCESS | 1676 | 04:07:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 04:07:51 INFO - PROCESS | 1676 | RSS: Main: 385241088 04:07:51 INFO - PROCESS | 1676 | 04:07:53 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 04:07:53 INFO - PROCESS | 1676 | RSS: Main: 286081024 04:07:53 INFO - PROCESS | 1676 | 04:07:56 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:07:56 INFO - PROCESS | 1676 | RSS: Main: 290287616 04:07:56 INFO - PROCESS | 1676 | 04:07:59 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 04:08:00 INFO - PROCESS | 1676 | RSS: Main: 325226496 04:08:00 INFO - PROCESS | 1676 | 04:08:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 04:08:03 INFO - PROCESS | 1676 | RSS: Main: 329302016 04:08:03 INFO - PROCESS | 1676 | 04:08:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 04:08:07 INFO - PROCESS | 1676 | RSS: Main: 328650752 04:08:07 INFO - PROCESS | 1676 | 04:08:08 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:08:09 INFO - PROCESS | 1676 | RSS: Main: 319565824 04:08:09 INFO - PROCESS | 1676 | 04:08:11 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:08:11 INFO - PROCESS | 1676 | RSS: Main: 325906432 04:08:11 INFO - PROCESS | 1676 | 04:08:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:08:14 INFO - PROCESS | 1676 | RSS: Main: 329834496 04:08:14 INFO - PROCESS | 1676 | 04:08:17 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:08:17 INFO - PROCESS | 1676 | RSS: Main: 334082048 04:08:17 INFO - PROCESS | 1676 | 04:08:20 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:08:21 INFO - PROCESS | 1676 | RSS: Main: 331493376 04:08:21 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 04:08:23 INFO - PROCESS | 1676 | RSS: Main: 396615680 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | __start_tp_report 04:08:23 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 04:08:23 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 04:08:23 INFO - PROCESS | 1676 | |i|pagename|runs| 04:08:23 INFO - PROCESS | 1676 | |0;ai-astar;113;111;112;113;113;114;121;113;112;112 04:08:23 INFO - PROCESS | 1676 | |1;audio-beat-detection;158;121;146;171;119;148;169;120;169;120 04:08:23 INFO - PROCESS | 1676 | |2;audio-dft;185;186;187;187;186;181;181;183;181;185 04:08:23 INFO - PROCESS | 1676 | |3;audio-fft;76;71;87;90;55;58;58;78;84;54 04:08:23 INFO - PROCESS | 1676 | |4;audio-oscillator;92;97;135;78;78;79;125;80;77;129 04:08:23 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;94;130;113;87;89;85;89;86;89;85 04:08:23 INFO - PROCESS | 1676 | |6;imaging-darkroom;104;127;95;95;94;94;95;141;146;95 04:08:23 INFO - PROCESS | 1676 | |7;imaging-desaturate;86;84;85;83;84;83;84;85;85;83 04:08:23 INFO - PROCESS | 1676 | |8;json-parse-financial;58;61;59;59;59;59;60;75;60;57 04:08:23 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;63;70;66;68;71;67;68;66;65;66 04:08:23 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;82;81;81;82;82;81;81;80;82;83 04:08:23 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;142;139;139;137;139;137;137;138;174;145 04:08:23 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;181;170;171;170;166;169;169;168;172;221 04:08:23 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;68;69;69;68;68;67;68;69;66;66 04:08:23 INFO - PROCESS | 1676 | __end_tp_report 04:08:23 INFO - PROCESS | 1676 | __start_cc_report 04:08:23 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1936 04:08:23 INFO - PROCESS | 1676 | __end_cc_report 04:08:23 INFO - PROCESS | 1676 | __startTimestamp1461236903492__endTimestamp 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | ------- Summary: start ------- 04:08:23 INFO - PROCESS | 1676 | Number of tests: 14 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:113.40 Median:113.00 stddev:2.80 (2.5%) stddev-sans-first:2.96 04:08:23 INFO - PROCESS | 1676 | Values: 113.0 111.0 112.0 113.0 113.0 114.0 121.0 113.0 112.0 112.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:144.10 Median:153.00 stddev:22.36 (14.6%) stddev-sans-first:23.15 04:08:23 INFO - PROCESS | 1676 | Values: 158.0 121.0 146.0 171.0 119.0 148.0 169.0 120.0 169.0 120.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.50 stddev:2.49 (1.3%) stddev-sans-first:2.62 04:08:23 INFO - PROCESS | 1676 | Values: 185.0 186.0 187.0 187.0 186.0 181.0 181.0 183.0 181.0 185.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:71.10 Median:77.00 stddev:13.92 (18.1%) stddev-sans-first:14.65 04:08:23 INFO - PROCESS | 1676 | Values: 76.0 71.0 87.0 90.0 55.0 58.0 58.0 78.0 84.0 54.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:78.50 stddev:23.60 (30.1%) stddev-sans-first:24.96 04:08:23 INFO - PROCESS | 1676 | Values: 92.0 97.0 135.0 78.0 78.0 79.0 125.0 80.0 77.0 129.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:88.00 stddev:14.91 (16.9%) stddev-sans-first:15.82 04:08:23 INFO - PROCESS | 1676 | Values: 94.0 130.0 113.0 87.0 89.0 85.0 89.0 86.0 89.0 85.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:108.60 Median:94.50 stddev:21.01 (22.2%) stddev-sans-first:22.22 04:08:23 INFO - PROCESS | 1676 | Values: 104.0 127.0 95.0 95.0 94.0 94.0 95.0 141.0 146.0 95.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.50 stddev:1.03 (1.2%) stddev-sans-first:0.87 04:08:23 INFO - PROCESS | 1676 | Values: 86.0 84.0 85.0 83.0 84.0 83.0 84.0 85.0 85.0 83.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:59.50 stddev:5.14 (8.6%) stddev-sans-first:5.36 04:08:23 INFO - PROCESS | 1676 | Values: 58.0 61.0 59.0 59.0 59.0 59.0 60.0 75.0 60.0 57.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:67.50 stddev:2.36 (3.5%) stddev-sans-first:2.01 04:08:23 INFO - PROCESS | 1676 | Values: 63.0 70.0 66.0 68.0 71.0 67.0 68.0 66.0 65.0 66.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:82.00 stddev:0.85 (1.0%) stddev-sans-first:0.88 04:08:23 INFO - PROCESS | 1676 | Values: 82.0 81.0 81.0 82.0 82.0 81.0 81.0 80.0 82.0 83.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:139.00 stddev:11.28 (8.1%) stddev-sans-first:11.97 04:08:23 INFO - PROCESS | 1676 | Values: 142.0 139.0 139.0 137.0 139.0 137.0 137.0 138.0 174.0 145.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:170.50 stddev:16.41 (9.6%) stddev-sans-first:17.29 04:08:23 INFO - PROCESS | 1676 | Values: 181.0 170.0 171.0 170.0 166.0 169.0 169.0 168.0 172.0 221.0 04:08:23 INFO - PROCESS | 1676 | 04:08:23 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 04:08:23 INFO - PROCESS | 1676 | Values: 68.0 69.0 69.0 68.0 68.0 67.0 68.0 69.0 66.0 66.0 04:08:23 INFO - PROCESS | 1676 | -------- Summary: end -------- 04:08:23 INFO - PROCESS | 1676 | 04:08:24 INFO - TEST-INFO | 1676: exit 0 04:08:24 INFO - TEST-OK | kraken | took 58587ms 04:08:24 INFO - SUITE-END | took 1173s 04:08:24 INFO - Completed test suite (00:19:34) 04:08:24 INFO - javascript benchmark 04:08:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3417.2652879630878, 3457.757956943015, 3509.4560343146823, 3509.5613048368964, 3533.2403533240363, 5614.185943038257, 5695.259616249882, 5700.201971723956, 5713.45722156395, 5718.485336319731, 5708.914366284509, 5737.426257374261, 5760.596758226926, 5859.296884373881, 5925.377835327293, 5159.174532074869, 5191.766370513336, 5208.8437346879655, 5223.425423203445, 5320.787168513255, 5285.652158699526, 5299.735013249341, 5325.946740532584, 5357.544608708265, 5363.141897296433, 4263.424437700902, 4688.085823264465, 4895.55939965403, 5174.353205849269, 5231.869203269911, 4278.6363159131515, 5240.292560504359, 5253.9737301313635, 5260.789568417259, 5283.471652834724, 5118.2578526113875, 5202.557782588485, 5229.816956406507, 5242.816501422432, 5245.86885327866, 5591.580631452616, 5620.662760234399, 5660.632058916157, 5699.914501282484, 5711.7144142792695, 6241.251049873998, 6248.875202463555, 6289.496840252768, 6290.779822706183, 6308.589941653778, 6135.9079613805825, 6254.155688981975, 6280.0, 6328.936710632881, 6335.588186767845, 5749.425057494259, 5794.478496935211, 5838.8540286492325, 5843.444872737083, 5859.882802343972, 6281.403266689657, 6287.371262873768, 6290.779822706275, 6303.432691057827, 6309.684515774193, 6110.358412366726, 6113.663748493876, 6120.14317995481, 6123.173371594867, 6128.2033335666965, 6099.69501524922, 6185.474234690012, 6246.281677607042, 6265.404786545271, 6290.811275661737, 6241.095041219089, 6245.375462453809, 6247.312795592481, 6248.062790581449, 6278.058291256257, 6231.501479881598, 6283.717232724538, 6284.0, 6291.748330066746, 6303.117563541108, 4829.203181475017, 4927.249667954925, 4944.480829512921, 4949.826756063592, 4949.876253093629, 4393.29707246839, 4458.665600080007, 4502.16709908668, 4509.594136527729, 4514.593686568159, 5701.657900525982, 5710.857228569236, 5716.256886604715, 5717.285339332583, 5725.1698503716725, 6146.600470969463, 6175.59858609189, 6205.844853878689, 6226.066090086523, 6277.686115694197, 5670.206171136044, 5673.120666296731, 5681.318241811009, 5699.230603868507, 5730.025895597759, 5322.494363035506, 5356.491133342326, 5377.059014672417, 5385.596080293915, 5393.8112166074, 6320.083587879732, 6412.487001039905, 6415.037744338293, 6429.22849258092, 6431.517636177306, 6199.318075011835, 6236.03341482071, 6250.249970003629, 6253.155823963707, 6265.154204182377, 5320.813771517978, 6170.660613666291, 6316.210473690789, 6401.775937842153, 6544.4764418845425, 6065.150878877049, 6192.159846439231, 6220.813375598739, 6247.406496382836, 6249.750009999549, 6279.905801413074, 6289.433950944437, 6303.023031430135, 6313.2424109107205, 6341.0, 4571.862844114682, 5762.510186634016, 5964.135200396005, 6087.665178415053, 6128.142060111677, 5998.0, 6098.32918378969, 6128.8774224516155, 6151.138840562415, 6158.230221222348, 5950.018246989198, 5971.402859713994, 5984.640921544721, 5995.850103747442, 6021.42796434338, 5935.436133567304, 6006.819795406145, 6099.4815440688035, 6131.437835141539, 6139.0321984548755, 6055.364186760326, 6057.969710151421, 6065.848353791014, 6067.484263837623, 6086.056661217518], "value": 5698.908510067092, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.8103482746211, 530.4562823106315, 584.617075815341, 613.8618810767575, 726.8443175351191, 29838.254043648918, 30570.0, 37410.0, 38602.648907288254, 38673.80663096681, 39540.406893896616, 40026.199476010464, 40199.0, 40321.0, 40336.59663403365, 9475.905240947588, 9742.318037737361, 9749.366291191085, 9749.658761943334, 9753.463559504224, 9525.618975240983, 9635.470049147294, 9638.469884156368, 9647.276454265922, 9650.08324209199, 9625.085616866387, 9685.515724213761, 9687.903120968806, 9714.319997600136, 9736.123748862601, 9619.134277914985, 9644.421334719938, 9649.276304277208, 9650.179734722487, 9668.70993870185, 9417.340786144972, 9543.33196676233, 9544.761380965461, 9549.0, 9555.569999350046, 39339.01652458712, 39921.40117898234, 39945.400818987735, 39948.80025599882, 39955.60044399548, 3149.992002559182, 3194.4090343286566, 3208.449581506581, 3610.3862343401684, 3988.521377434726, 28172.718272817216, 28426.28934276639, 28467.57298640522, 29132.27169320763, 29171.708282917323, 2684.396010897552, 2713.986430067857, 2714.4571085782923, 2784.665840099201, 2791.21845883151, 450925.7453712743, 452983.0, 454360.45639543515, 454834.0, 457007.0, 35297.29405411929, 36397.45403818945, 36452.0, 59983.700081499315, 60304.698476508216, 34677.13307167341, 34850.0, 34896.65103348985, 34958.47562286568, 34980.65019349825, 14220.075695079993, 14435.473792086343, 14443.0, 14459.349329280207, 14466.638334041732, 12072.15494915365, 12142.271463712206, 12165.878341216652, 12183.0, 12190.756184876253, 8121.269085782308, 8163.918360816435, 8186.877196842052, 8230.670773169126, 8265.421420500628, 2860.2420358604986, 3377.8142202178624, 3390.4066788311948, 3399.5070714746225, 3422.178677117474, 826.962786674601, 998.1266391907081, 1053.273241463403, 1094.6153116307878, 1197.3115458611333, 29111.85444072766, 29235.56146657802, 29399.559006614916, 29925.551116733266, 30048.098557043322, 1060.1148041385375, 1066.195022757813, 1108.51225460797, 1109.9223054386116, 1112.093643680398, 11817.468213930393, 15841.287142078634, 15867.444639437565, 15983.408364617244, 16185.271662775203, 511828.0, 520943.0, 522114.0, 522404.0, 523892.0, 53977.46022539724, 56653.43346566564, 56688.866222675315, 56692.0, 56925.71537142288, 1335.3990704182963, 1375.40170026039, 1376.5526203983745, 1482.970340593182, 1484.6733718581895, 23231.0, 26154.0, 29376.41247175087, 30137.397252055274, 30503.0, 50569.494305056476, 55598.16602751043, 57084.71457642685, 57271.42728572828, 57292.71353643205, 539661.0, 541221.0, 548188.0, 549124.0, 549884.0, 33872.830635846665, 34784.82607586946, 41782.16435671331, 42545.361819572114, 42559.57440425556, 42094.57905420907, 42158.1568368637, 42526.0, 42799.1440171201, 43676.781616091714, 34358.0, 35316.64683353133, 35935.82032089823, 36114.27771444609, 36254.637453625124, 34564.48153277753, 35050.474242886885, 35765.64234357623, 35975.280494389444, 35975.640243598275, 32046.03861884147, 32612.673873261912, 32626.51060234146, 32813.83593082019, 36035.459468107474, 23991.640125397786, 24771.87614061918, 30977.690223098383, 30982.22544436317, 31039.0, 19264.61470770605, 23054.962526686002, 23632.645510317703, 24090.02762526822, 24373.3906652328], "value": 18138.781421663334, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.2233976726082, 393.13118009199667, 393.26852055177375, 395.81594558530276, 405.8863518214901, 19842.40472785817, 20790.168393264288, 20858.478538036557, 20871.373858784224, 21410.785892141073, 17663.82336176638, 19346.613067738635, 20485.38543843687, 21289.893550532226, 21296.148154073817, 5379.919301210475, 5932.614380065282, 6105.664188469633, 6106.572539922207, 6107.450329470346, 6014.308354539218, 6030.728617212236, 6091.329953705089, 6105.664188469633, 6114.816555503342, 5217.895642087156, 5270.5520030797425, 5322.361316642008, 5325.7869685212545, 5329.573634109282, 5291.259223708684, 5301.284326615915, 5302.549283310904, 5320.733963301839, 6022.548308876808, 5167.509086636764, 5227.869303267411, 5266.657667251617, 5267.604929630254, 5269.0, 13127.277999710011, 14060.367283472267, 14062.156270623693, 14081.788773168411, 26433.603495947576, 2146.3346362627635, 2153.192552792703, 2490.306591517767, 2596.7273436289297, 2825.350169461015, 9528.428294302295, 9834.557444914924, 10244.846327305097, 10248.180145588334, 10282.7429314267, 1448.2686243446992, 1701.5235733994502, 1716.4164184177314, 1746.56335916021, 1930.04462790918, 122409.0, 127681.99999999907, 127695.0, 128620.0, 130380.0, 11505.884941150649, 11633.243839150597, 11646.24299420535, 11675.649730508097, 11844.230125041844, 15263.618409539851, 15303.846961530466, 15322.770158447633, 15335.61660958485, 15348.846511534743, 8586.613602387908, 8599.0, 8630.827383452295, 8635.654573817103, 8759.12408759119, 6846.828829279209, 8379.664813407395, 8486.787830304169, 8492.532910689848, 8506.532140732195, 3910.8463003414063, 4546.704464209815, 4634.0, 4646.8141274348645, 4711.434627844612, 1770.7166853303668, 1877.6620208362353, 1899.9075531569404, 1948.1038722187668, 1962.391658585843, 868.600443795861, 879.8020445399862, 898.6789419553246, 918.912703293186, 979.8003482182546, 18871.0, 19989.500262493555, 20311.492212694506, 20969.370918872457, 21232.36302910915, 926.2636204217663, 936.9063093690713, 942.1379437814323, 973.6786860336072, 980.5930538826307, 10859.891401086046, 11168.329900206014, 11183.161262905152, 11191.160662950311, 11198.776024479628, 598610.0, 606802.9659851673, 619130.0, 628532.0, 630747.0, 119939.0, 129726.0, 132531.0, 135579.0, 137372.0, 1008.2690049714046, 1011.327467234281, 1030.860833787444, 1032.0608246495653, 1032.7779527401644, 16648.50163485292, 18325.0, 18674.533136671147, 18829.623407531497, 19048.04759762034, 41244.0, 51360.0, 51611.0, 53060.469395305554, 53101.7344913273, 65457.0, 73090.26909730835, 76829.0, 77858.0, 78659.0, 7076.787696369117, 7680.385569154566, 8132.796680083045, 8192.139825318256, 8515.105913878953, 10751.354918704903, 12382.546657499619, 12400.721750995892, 12503.687407814878, 12829.166104203197, 10717.839232411365, 10796.082333001468, 10802.67591972242, 10950.94524527404, 11008.669739907815, 9483.861936567657, 9693.466859322805, 10389.220808439548, 10544.736381590521, 10947.945260273647, 10162.187025038127, 10938.070264027328, 10970.177236707119, 10975.890241097806, 11382.54469821198, 8076.959615202121, 8166.510009399218, 8212.548309843016, 8221.712240071569, 8483.660653573788, 10563.788724225626, 10978.560857565928, 11108.222424430212, 11224.0, 11230.157738169506], "value": 9372.996134151832, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.4918629293456, 1414.4625042483854, 1591.649837035852, 1814.3559036542026, 1995.2118913029344, 7870.331021863141, 8582.527960962145, 8806.471611703302, 8815.647374105029, 8818.250448711851, 8450.577471126435, 8702.477851328924, 8704.390692651517, 8711.86932196016, 8715.997660269071, 1927.575933294673, 2245.9775402246014, 2347.58917189492, 2419.794317483016, 2446.522928029035, 2462.335169504233, 2489.4025433895918, 2489.7136829264687, 2496.3883848457062, 2498.475320182764, 2429.186222615417, 2497.2383423055962, 2497.7502249775057, 2500.1874390822964, 2502.887370068351, 2149.7742737012622, 2400.699912510936, 2455.312512496504, 2475.851448913071, 2477.8513289202524, 2447.9143229986867, 2456.7051953765485, 2480.838745481538, 2487.825852190347, 2488.2783992642294, 8132.918670813276, 8223.972003499563, 8230.83538329236, 8237.217464340938, 8241.546714930677, 5119.10415677255, 5347.0, 5353.437889021675, 5367.1680889462195, 5382.138857782775, 3293.8517766700556, 3427.4516077427493, 3489.6335884731993, 3490.371733088043, 3492.1269682579355, 963.7831487915233, 986.2603047714215, 997.551102004101, 999.2555546118116, 999.9300048996573, 11470.0, 11617.825732614017, 11624.476898539502, 11625.186236963416, 11626.651200463999, 4493.483249426358, 4517.277235642281, 4614.76926153691, 4663.650226232979, 4697.553732395417, 9110.49892255919, 9121.45271283725, 9124.726258212264, 9136.6802161924, 9140.131687489678, 5228.869278268074, 5238.109521381374, 5246.554042906396, 5274.841754747364, 5278.419373868871, 4375.2780791169735, 4546.40896683429, 4784.736839473792, 4917.9918116785975, 4937.950620493749, 3320.8543052646796, 3660.286244182359, 3672.8163591820303, 3673.7428380013143, 3790.639889210521, 1214.5384753793503, 1222.9571964981183, 1226.5707002549036, 1236.5981056156604, 1252.511520507003, 742.4913933955258, 842.6839935024366, 855.2730179347504, 869.4392117084445, 880.564120760215, 4976.328195693607, 5021.84934451967, 5507.9449205507435, 5727.742251598688, 5795.913061304083, 450.1739308369114, 454.8317122664569, 459.5358687725355, 465.38569088802456, 473.14597152140715, 3079.6612372638992, 3584.390653588896, 3701.550098651669, 3783.5270591176104, 3795.0512371907025, 8907.376483646083, 10623.946880265703, 10713.571457141783, 10838.945805270923, 10877.292975956538, 10641.627543035956, 10649.520771565298, 10750.78498430027, 10791.352518848737, 10831.783364332827, 8123.3095186908595, 8139.186081391814, 8223.753287401269, 8247.546384948886, 8248.711295104762, 5198.402183748917, 5359.713668719557, 5410.161424979135, 5664.546836253007, 5695.202671625892, 9404.0, 9723.805523889625, 9827.459489727848, 9839.40963542161, 9894.45580493051, 7649.235076492307, 9884.604615815288, 9947.502624868872, 10020.298579099393, 10035.49822508857, 6815.318468153145, 7043.7534686285735, 7232.63836808147, 7248.275172482709, 7384.409247260313, 7167.892481612885, 8060.31487323584, 8139.430239883388, 8159.26566609008, 8233.135520770233, 6290.339514350927, 7222.855542889218, 7298.4891057625455, 7347.70609175627, 7453.440991925519, 7154.320339567733, 7170.96414517924, 7229.204787473269, 7261.213406536794, 7305.707771689073, 6707.932920670731, 6847.281035491401, 6850.45196384298, 6853.0405743195, 6871.690773915086, 4715.599174070242, 4780.067886762048, 4805.615550756001, 4868.123737727243, 5012.348394708664, 4776.474587795409, 4784.593309568726, 4840.2255639097575, 4846.927296090491, 4920.704757714549], "value": 4387.373541716522, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.6319931292069, 305.42579949694573, 313.1466753097809, 325.78009843355727, 326.8398484742477, 5987.131865879445, 6007.819765407039, 6020.367861374559, 6022.698865056752, 6069.241344831896, 5605.915911261324, 5732.254806875111, 5840.591158618899, 5843.620164689303, 5956.51543846849, 3702.2040261343805, 4140.441040459537, 4515.729056256635, 4535.773211339436, 4569.246074397721, 4049.4330793688905, 4130.459767243139, 4505.301678239878, 4512.142692888341, 4527.822766080826, 4376.627986621141, 4426.29179331307, 4513.616342610882, 4517.525659805722, 4531.977340113295, 3704.814759262026, 4448.644108471314, 4489.349044388562, 4502.932456013163, 4511.894585826493, 3874.2832575973534, 3879.952412848543, 3883.417487376888, 3893.961060389388, 3921.901952451183, 5094.745262736886, 5322.840314790524, 5332.066888294533, 5434.239206511092, 5687.677365202435, 4682.461516925563, 4877.975625118729, 5045.571126454256, 5097.082525145473, 5105.540501354859, 6296.716647750816, 6307.905381419282, 6311.810645680637, 6323.30443651198, 6336.2396512418345, 3456.0, 4005.439238506611, 4158.981049642826, 4171.165766846643, 4189.371594260855, 5414.052540805344, 6426.646534440604, 6471.320511346288, 6546.93453065468, 6581.30896255891, 4211.599898009684, 4218.873433796991, 4219.873403797891, 4226.344916537942, 4253.680973926906, 3571.678548930609, 4265.701400901907, 4298.548652391454, 4298.591633794847, 4313.956860431355, 4618.237990731559, 5015.297858299841, 5037.002983224976, 5062.367204099488, 5064.417591976896, 5727.11229759388, 5988.580799344091, 6141.539384546177, 6364.936350636434, 6377.915754321776, 1320.6038188543398, 1374.5257886029303, 1394.2540740703676, 1460.1239256446047, 1490.299559207171, 939.6523286384082, 951.219999600335, 956.8756061712072, 958.2812890332251, 964.5996911281879, 5801.564882633819, 5805.187273781674, 5818.51973980033, 5820.621659592113, 5850.678212698343, 6412.198475190601, 6414.390632889868, 6432.324605916311, 6453.741850326029, 6459.257185423737, 5153.845384638392, 5281.551068159172, 5688.800891968761, 5759.65442073477, 5795.507381872588, 2894.9276268093045, 3291.308825146746, 3357.127146941764, 3399.6600339965808, 3413.2661477782394, 6339.334369891188, 6379.808605741835, 6623.7350505980185, 6650.068990341356, 6710.463162946952, 6523.575967562188, 6569.57297775643, 6570.704318305592, 6583.604983700993, 6587.736490540325, 6425.807225783234, 6651.1423374443675, 6669.099671544376, 6677.599344039276, 6678.432333251728, 5391.786847959178, 6353.809385718436, 6406.807795766134, 6415.518836087405, 6435.581687190318, 6250.4687101595055, 6277.30949595535, 6294.685265736786, 6345.587536810092, 6352.841178970562, 5780.826575202751, 6384.585001974857, 6650.800475985729, 6663.433608143168, 6705.6647167642395, 4159.958400416078, 4193.370994350872, 4196.5588217661225, 4207.600277973587, 4215.388768628593, 6100.115483254992, 6383.39357761012, 6394.488440924808, 6402.90395644075, 6414.2855001574735, 5032.547070763649, 5809.50619197373, 6286.9371306288185, 6351.2060992375955, 6401.87196256063, 5287.021900948337, 6353.205849268841, 6363.522735794926, 6365.64988925595, 6382.776602818879, 5896.587238893408, 6157.9692101540995, 6159.260888693386, 6168.537359697951, 6198.706478602946, 6331.176946996861, 6331.303556608677, 6386.584871983307, 6387.776427825004, 6395.744170233324, 6009.055562774121, 6233.812985610439, 6250.4374606285655, 6269.216347956505, 6269.279032911274], "value": 4559.933487170306, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.1445435388348, 692.8648913461875, 739.9926000739998, 769.253823790923, 807.1283014344508, 14766.0, 14819.036762610436, 14994.625134371647, 15010.549683509498, 15011.624709382271, 13475.393607287671, 14343.856561434382, 14401.92799036006, 14416.062955907884, 14470.855291447082, 728.3590440412456, 761.3338328962158, 911.3392790227066, 911.8130783189447, 923.1783712495851, 941.5857022909931, 944.1738478831023, 947.2422062350126, 947.2611363136764, 949.7150854743584, 989.3915242126077, 994.2741798487109, 995.1988649137442, 995.4326034160531, 996.0537489385077, 754.4944886925718, 945.2721404518483, 988.0465350936345, 989.6140505202976, 997.5112195024458, 738.8337624034602, 740.2153717059933, 740.9814754631124, 742.9108506979143, 743.0711610486892, 14981.925090374478, 15001.32494037771, 15003.849961500464, 15004.324805383783, 15033.398664053535, 12282.877171228263, 12451.501939922393, 12474.43865026076, 12678.492860285578, 12792.552260670831, 7549.849003019909, 9123.133302336268, 9138.497382644018, 9151.450912945245, 9162.908370916271, 3408.590969083701, 3471.3230919970615, 3476.391631464509, 3494.475828625701, 3662.249238906018, 75565.48869022589, 76239.6188019062, 77116.22883771146, 78326.60836695837, 79886.60056699737, 9397.248220142372, 9438.858417123749, 9440.763980900396, 9492.003339649238, 9517.0, 21441.249556265455, 21707.457313567287, 21710.674339884914, 21724.23965161212, 21730.0, 7642.4650274480255, 7677.155512893577, 7711.48099879586, 7935.761927142195, 8025.07711613172, 4068.389741538793, 4113.321301985199, 4170.874873753793, 4239.576042395735, 4411.911761764747, 3561.269939662363, 3659.2315613720884, 3661.761985470936, 3664.8350824212976, 3671.9816400917825, 549.4862303746011, 635.9682015899186, 659.535027805396, 662.5130529061137, 688.6281408039703, 502.4397796456924, 512.4900723779863, 514.5883293365292, 536.3376230355508, 570.1789423230534, 7587.810304742426, 7643.7324693636565, 7676.385889128856, 7751.651175697107, 7931.246531579491, 547.622140722908, 552.3068548971015, 557.7211394302849, 558.4778232352763, 559.0803128853095, 3613.385724426854, 3626.7642603230706, 3646.4894914711963, 3681.4661874028116, 3897.7271590988357, 92172.07827921635, 93787.0, 94292.0, 94406.0, 94603.0539694608, 74392.0, 75082.6245868778, 75206.62396688091, 75604.62197688976, 76309.61845190739, 65811.0, 66286.00570991535, 67426.0, 67684.32315676879, 67773.0, 5843.853903652443, 7238.203797582157, 7326.3406293433845, 7499.362554182955, 7612.3910087194, 41850.37224441575, 43057.354139688556, 43387.0, 43568.782156089015, 43715.78142109396, 62396.37603624087, 63993.3600664006, 64194.07417777474, 64460.677696611216, 64585.677071614344, 9890.0, 9890.159336456481, 9948.303618746619, 10094.798104038025, 10268.0, 10342.689719308433, 10368.948155259175, 10632.362058276529, 10710.0, 11062.170337224901, 9918.504074796376, 9978.650747223812, 10234.232432567438, 10237.795244094927, 10261.0, 9847.867495237853, 10154.086132248132, 10156.746081348025, 10182.847257290998, 10244.64143754965, 9745.415275083518, 10062.245331600014, 10070.949645251727, 10071.244656650633, 10172.338797978107, 8340.541270229953, 8412.284955778827, 8509.276711479593, 8630.78423039404, 8712.998005229227, 8439.240468357877, 8480.575971201291, 8742.956285218534, 9207.17135457812, 9320.95339523298], "value": 6636.7013382964415, "unit": "score"}], "name": "dromaeo_css", "value": 7105.132316707079, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 112.0, 113.0, 113.0, 114.0, 121.0, 113.0, 112.0, 112.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 121.0, 146.0, 171.0, 119.0, 148.0, 169.0, 120.0, 169.0, 120.0], "value": 144.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 187.0, 187.0, 186.0, 181.0, 181.0, 183.0, 181.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 71.0, 87.0, 90.0, 55.0, 58.0, 58.0, 78.0, 84.0, 54.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 97.0, 135.0, 78.0, 78.0, 79.0, 125.0, 80.0, 77.0, 129.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 130.0, 113.0, 87.0, 89.0, 85.0, 89.0, 86.0, 89.0, 85.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 127.0, 95.0, 95.0, 94.0, 94.0, 95.0, 141.0, 146.0, 95.0], "value": 108.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 83.0, 84.0, 83.0, 84.0, 85.0, 85.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 59.0, 59.0, 59.0, 59.0, 60.0, 75.0, 60.0, 57.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 66.0, 68.0, 71.0, 67.0, 68.0, 66.0, 65.0, 66.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 82.0, 82.0, 81.0, 81.0, 80.0, 82.0, 83.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 139.0, 139.0, 137.0, 139.0, 137.0, 137.0, 138.0, 174.0, 145.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 170.0, 171.0, 170.0, 166.0, 169.0, 169.0, 168.0, 172.0, 221.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 68.0, 68.0, 67.0, 68.0, 69.0, 66.0, 66.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1492.7, "alertThreshold": 2.0}]} 04:08:24 INFO - Return code: 0 04:08:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:08:24 ERROR - # TBPL SUCCESS # 04:08:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:08:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:08:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:08:24 INFO - 2016-04-21 04:08:24.802 screenresolution[1680:9915] starting screenresolution argv=screenresolution get 04:08:24 INFO - 2016-04-21 04:08:24.805 screenresolution[1680:9915] Display 0: 1600x1200x32@60 04:08:24 INFO - 2016-04-21 04:08:24.810 screenresolution[1681:9918] starting screenresolution argv=screenresolution list 04:08:24 INFO - Available Modes on Display 0 04:08:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:08:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:08:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:08:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:08:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:08:25 INFO - Intel Iris: 04:08:25 INFO - Chipset Model: Intel Iris 04:08:25 INFO - Type: GPU 04:08:25 INFO - Bus: Built-In 04:08:25 INFO - VRAM (Dynamic, Max): 1536 MB 04:08:25 INFO - Vendor: Intel (0x8086) 04:08:25 INFO - Device ID: 0x0a2e 04:08:25 INFO - Revision ID: 0x0009 04:08:25 INFO - Displays: 04:08:25 INFO - Display: 04:08:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:08:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:08:25 INFO - Display Serial Number: 1600x1200 60 04:08:25 INFO - Main Display: Yes 04:08:25 INFO - Mirror: Off 04:08:25 INFO - Online: Yes 04:08:25 INFO - Rotation: Supported 04:08:25 INFO - Return code: 0 04:08:25 INFO - Running post-action listener: _resource_record_post_action 04:08:25 INFO - Running post-run listener: _resource_record_post_run 04:08:26 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 37864960; Write bytes: 693342720; Read time: 434; Write time: 1838 04:08:26 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 373559808; Write bytes: 367171072; Read time: 27908; Write time: 649 04:08:26 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 36906496; Write bytes: 296770560; Read time: 418; Write time: 1133 04:08:26 INFO - Running post-run listener: _upload_blobber_files 04:08:26 INFO - Blob upload gear active. 04:08:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:08:26 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 04:08:26 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'] 04:08:26 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 04:08:26 INFO - (blobuploader) - INFO - Open directory for files ... 04:08:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:08:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:08:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:08:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:08:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:08:27 INFO - (blobuploader) - INFO - Done attempting. 04:08:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:08:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:08:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:08:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:08:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:08:28 INFO - (blobuploader) - INFO - Done attempting. 04:08:28 INFO - (blobuploader) - INFO - Iteration through files over. 04:08:28 INFO - Return code: 0 04:08:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:08:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:08:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/078ddcef482b9e36be2f4b3bdd8e561f0a98d83a0a9fe54f1667bda8fff32f624ebce6740bef07605f2bca18e379bc59d312238fe994f7b1320f2106bc77d919", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6ef76b64d80868c640ed6fb8307a88afb2511726263c8aa3a59024b33382c7105ef901964718dd0e0084ce5d7ad3b47ed42df0e1847a37cf74558f703b24841"} 04:08:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:08:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:08:28 INFO - Contents: 04:08:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/078ddcef482b9e36be2f4b3bdd8e561f0a98d83a0a9fe54f1667bda8fff32f624ebce6740bef07605f2bca18e379bc59d312238fe994f7b1320f2106bc77d919", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6ef76b64d80868c640ed6fb8307a88afb2511726263c8aa3a59024b33382c7105ef901964718dd0e0084ce5d7ad3b47ed42df0e1847a37cf74558f703b24841"} 04:08:28 INFO - Running post-run listener: copy_logs_to_upload_dir 04:08:28 INFO - Copying logs to upload dir... 04:08:28 INFO - mkdir: /builds/slave/test/build/upload/logs 04:08:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.620731 ========= master_lag: 6.65 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-04-21 04:08:34.938977) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 50 secs) (at 2016-04-21 04:08:34.943839) ========= 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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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/078ddcef482b9e36be2f4b3bdd8e561f0a98d83a0a9fe54f1667bda8fff32f624ebce6740bef07605f2bca18e379bc59d312238fe994f7b1320f2106bc77d919", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6ef76b64d80868c640ed6fb8307a88afb2511726263c8aa3a59024b33382c7105ef901964718dd0e0084ce5d7ad3b47ed42df0e1847a37cf74558f703b24841"} build_url:https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011380 build_url: 'https://queue.taskcluster.net/v1/task/bq6NRWeaSsyU__UsIJk-Rg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/078ddcef482b9e36be2f4b3bdd8e561f0a98d83a0a9fe54f1667bda8fff32f624ebce6740bef07605f2bca18e379bc59d312238fe994f7b1320f2106bc77d919", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6ef76b64d80868c640ed6fb8307a88afb2511726263c8aa3a59024b33382c7105ef901964718dd0e0084ce5d7ad3b47ed42df0e1847a37cf74558f703b24841"}' ========= master_lag: 50.19 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 50 secs) (at 2016-04-21 04:09:25.142090) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 17 secs) (at 2016-04-21 04:09:25.142416) ========= 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.51Mx64yTIP/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.7AHGZ3aETQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.005092 ========= master_lag: 17.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 17 secs) (at 2016-04-21 04:09:42.237530) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-21 04:09:42.238001) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-21 04:09:43.731923) ========= ========= Total master_lag: 73.98 =========