builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0131 starttime: 1460722652.77 results: success (0) buildid: 20160415030231 builduid: 8a60b58d15a64c7fa48aa9b7f58805cc revision: afd82f887093e5e9e4015115ca5795ec82a6f732 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:32.769700) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:32.770114) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:32.770400) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:32.791456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:32.791868) ========= 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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-15 05:17:32-- 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% 7.37M=0.002s 2016-04-15 05:17:32 (7.37 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087170 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:32.895259) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:32.895580) ========= 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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.019417 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:32.949734) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 05:17:32.950090) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev afd82f887093e5e9e4015115ca5795ec82a6f732 --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 afd82f887093e5e9e4015115ca5795ec82a6f732 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FuowKzF5zz/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.YS1gnOZqWJ/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-15 05:17:33,025 truncating revision to first 12 chars 2016-04-15 05:17:33,025 Setting DEBUG logging. 2016-04-15 05:17:33,025 attempt 1/10 2016-04-15 05:17:33,025 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/afd82f887093?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 05:17:33,980 unpacking tar archive at: mozilla-central-afd82f887093/testing/mozharness/ program finished with exit code 0 elapsedTime=1.206936 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 05:17:34.177809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:34.178147) ========= script_repo_revision: afd82f887093e5e9e4015115ca5795ec82a6f732 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:34.178564) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:34.178854) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 05:17:34.197253) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-04-15 05:17:34.197610) ========= /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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:17:34 INFO - MultiFileLogger online at 20160415 05:17:34 in /builds/slave/test 05:17:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:17:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:17:34 INFO - {'append_to_log': False, 05:17:34 INFO - 'base_work_dir': '/builds/slave/test', 05:17:34 INFO - 'blob_upload_branch': 'Firefox', 05:17:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:17:34 INFO - 'branch': 'Firefox', 05:17:34 INFO - 'buildbot_json_path': 'buildprops.json', 05:17:34 INFO - 'config_files': ('talos/mac_config.py',), 05:17:34 INFO - 'default_actions': ('clobber', 05:17:34 INFO - 'read-buildbot-config', 05:17:34 INFO - 'download-and-extract', 05:17:34 INFO - 'populate-webroot', 05:17:34 INFO - 'create-virtualenv', 05:17:34 INFO - 'install', 05:17:34 INFO - 'run-tests'), 05:17:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:17:34 INFO - 'download_minidump_stackwalk': True, 05:17:34 INFO - 'download_symbols': 'ondemand', 05:17:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:17:34 INFO - 'tooltool.py': '/tools/tooltool.py', 05:17:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:17:34 INFO - '/tools/misc-python/virtualenv.py')}, 05:17:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:17:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:17:34 INFO - 'installer_path': 'installer.exe', 05:17:34 INFO - 'log_level': 'info', 05:17:34 INFO - 'log_name': 'talos', 05:17:34 INFO - 'log_to_console': True, 05:17:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:17:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:17:34 INFO - 'opt_config_files': (), 05:17:34 INFO - 'pip_index': False, 05:17:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:34 INFO - 'cmd': ('bash', 05:17:34 INFO - '-c', 05:17:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:34 INFO - 'enabled': True, 05:17:34 INFO - 'halt_on_failure': False, 05:17:34 INFO - 'name': 'check_screen_resolution'},), 05:17:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:17:34 INFO - 'cmd': ('bash', 05:17:34 INFO - '-c', 05:17:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:17:34 INFO - 'enabled': True, 05:17:34 INFO - 'halt_on_failure': False, 05:17:34 INFO - 'name': 'check_screen_resolution'},), 05:17:34 INFO - 'run_cmd_checks_enabled': True, 05:17:34 INFO - 'sps_profile': False, 05:17:34 INFO - 'sps_profile_interval': 0, 05:17:34 INFO - 'suite': 'dromaeojs', 05:17:34 INFO - 'system_bits': '32', 05:17:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:17:34 INFO - 'title': 't-yosemite-r7-0131', 05:17:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:17:34 INFO - 'use_talos_json': True, 05:17:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:17:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:17:34 INFO - 'work_dir': 'build'} 05:17:34 INFO - ##### 05:17:34 INFO - ##### Running clobber step. 05:17:34 INFO - ##### 05:17:34 INFO - Running pre-action listener: _resource_record_pre_action 05:17:34 INFO - Running main action method: clobber 05:17:34 INFO - rmtree: /builds/slave/test/build 05:17:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:17:35 INFO - Running post-action listener: _resource_record_post_action 05:17:35 INFO - ##### 05:17:35 INFO - ##### Running read-buildbot-config step. 05:17:35 INFO - ##### 05:17:35 INFO - Running pre-action listener: _resource_record_pre_action 05:17:35 INFO - Running main action method: read_buildbot_config 05:17:35 INFO - Using buildbot properties: 05:17:35 INFO - { 05:17:35 INFO - "project": "", 05:17:35 INFO - "product": "firefox", 05:17:35 INFO - "script_repo_revision": "production", 05:17:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:17:35 INFO - "repository": "", 05:17:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:17:35 INFO - "buildid": "20160415030231", 05:17:35 INFO - "stage_platform": "macosx64", 05:17:35 INFO - "basedir": "/builds/slave/test", 05:17:35 INFO - "nightly_build": "True", 05:17:35 INFO - "buildnumber": 217, 05:17:35 INFO - "slavename": "t-yosemite-r7-0131", 05:17:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:17:35 INFO - "platform": "yosemite_r7", 05:17:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:17:35 INFO - "branch": "mozilla-central", 05:17:35 INFO - "repo_path": "mozilla-central", 05:17:35 INFO - "revision": "afd82f887093e5e9e4015115ca5795ec82a6f732", 05:17:35 INFO - "pgo_build": "False", 05:17:35 INFO - "builduid": "8a60b58d15a64c7fa48aa9b7f58805cc", 05:17:35 INFO - "slavebuilddir": "test" 05:17:35 INFO - } 05:17:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:17:35 INFO - Running post-action listener: _resource_record_post_action 05:17:35 INFO - ##### 05:17:35 INFO - ##### Running download-and-extract step. 05:17:35 INFO - ##### 05:17:35 INFO - Running pre-action listener: _resource_record_pre_action 05:17:35 INFO - Running main action method: download_and_extract 05:17:35 INFO - mkdir: /builds/slave/test/build/tests 05:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:35 INFO - https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:17:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:17:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:17:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/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 05:17:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/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 05:17:37 INFO - Downloaded 1199 bytes. 05:17:37 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:17:37 INFO - Using the following test package requirements: 05:17:37 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:17:37 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:17:37 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:17:37 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'jsshell-mac.zip'], 05:17:37 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:17:37 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:17:37 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:17:37 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:17:37 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:17:37 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:17:37 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:17:37 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:17:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:37 INFO - https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:17:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:17:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/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 05:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/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 05:17:38 INFO - Downloaded 18034057 bytes. 05:17:38 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'] 05:17:38 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 05:17:42 INFO - Return code: 0 05:17:42 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 05:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:42 INFO - https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:17:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:17:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:17:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/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 05:17:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/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 05:17:43 INFO - Downloaded 18034057 bytes. 05:17:43 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'] 05:17:43 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 05:17:46 INFO - Return code: 0 05:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:46 INFO - https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:17:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:17:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:17:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/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 05:17:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/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 05:17:47 INFO - Downloaded 10841076 bytes. 05:17:47 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'] 05:17:47 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 05:17:48 INFO - Return code: 0 05:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:48 INFO - https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:17:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:17:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:17:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:17:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYdSSttMTriDPSTnQk5l8Q/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 05:17:51 INFO - Downloaded 110050469 bytes. 05:17:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:17:51 INFO - mkdir: /builds/slave/test/properties 05:17:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:17:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:17:51 INFO - Contents: 05:17:51 INFO - build_url:https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:17:52 INFO - Running post-action listener: _resource_record_post_action 05:17:52 INFO - Running post-action listener: set_extra_try_arguments 05:17:52 INFO - ##### 05:17:52 INFO - ##### Running populate-webroot step. 05:17:52 INFO - ##### 05:17:52 INFO - Running pre-action listener: _resource_record_pre_action 05:17:52 INFO - Running main action method: populate_webroot 05:17:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:17:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:17:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:17:52 INFO - '--tppagecycles', 05:17:52 INFO - '7'], 05:17:52 INFO - 'tests': ['tsvgm']}}, 05:17:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:17:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:17:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:17:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:17:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:52 INFO - 'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:17:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:52 INFO - 'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['damp', 'tps']}, 05:17:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:52 INFO - 'tests': ['damp', 'tps']}, 05:17:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['dromaeo_dom']}, 05:17:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:17:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['a11yr', 05:17:52 INFO - 'ts_paint', 05:17:52 INFO - 'tpaint', 05:17:52 INFO - 'sessionrestore', 05:17:52 INFO - 'sessionrestore_no_auto_restore', 05:17:52 INFO - 'tabpaint']}, 05:17:52 INFO - 'other-e10s': {'tests': ['a11yr', 05:17:52 INFO - 'ts_paint', 05:17:52 INFO - 'tpaint', 05:17:52 INFO - 'sessionrestore', 05:17:52 INFO - 'sessionrestore_no_auto_restore', 05:17:52 INFO - 'tabpaint']}, 05:17:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:17:52 INFO - 'ts_paint', 05:17:52 INFO - 'tpaint', 05:17:52 INFO - 'sessionrestore', 05:17:52 INFO - 'sessionrestore_no_auto_restore', 05:17:52 INFO - 'tabpaint']}, 05:17:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:17:52 INFO - 'ts_paint', 05:17:52 INFO - 'tpaint', 05:17:52 INFO - 'sessionrestore', 05:17:52 INFO - 'sessionrestore_no_auto_restore', 05:17:52 INFO - 'tabpaint']}, 05:17:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['a11yr', 05:17:52 INFO - 'ts_paint', 05:17:52 INFO - 'tpaint', 05:17:52 INFO - 'sessionrestore', 05:17:52 INFO - 'sessionrestore_no_auto_restore', 05:17:52 INFO - 'tabpaint']}, 05:17:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['a11yr', 05:17:52 INFO - 'ts_paint', 05:17:52 INFO - 'tpaint', 05:17:52 INFO - 'sessionrestore', 05:17:52 INFO - 'sessionrestore_no_auto_restore', 05:17:52 INFO - 'tabpaint']}, 05:17:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['tsvgx', 05:17:52 INFO - 'tsvgr_opacity', 05:17:52 INFO - 'tart', 05:17:52 INFO - 'tscrollx', 05:17:52 INFO - 'cart']}, 05:17:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:17:52 INFO - 'tsvgr_opacity', 05:17:52 INFO - 'tart', 05:17:52 INFO - 'tscrollx', 05:17:52 INFO - 'cart']}, 05:17:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:52 INFO - 'talos_options': ['--disable-e10s'], 05:17:52 INFO - 'tests': ['tp5o']}, 05:17:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:52 INFO - 'tests': ['tp5o']}, 05:17:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:52 INFO - 'talos_options': ['--disable-e10s', 05:17:52 INFO - '--xperf_path', 05:17:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:52 INFO - 'tests': ['tp5n']}, 05:17:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:17:52 INFO - 'talos_options': ['--xperf_path', 05:17:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:17:52 INFO - 'tests': ['tp5n']}}, 05:17:52 INFO - 'talos.zip': {'path': '', 05:17:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:17:52 INFO - Running post-action listener: _resource_record_post_action 05:17:52 INFO - ##### 05:17:52 INFO - ##### Running create-virtualenv step. 05:17:52 INFO - ##### 05:17:52 INFO - Running pre-action listener: _resource_record_pre_action 05:17:52 INFO - Running main action method: create_virtualenv 05:17:52 INFO - Creating virtualenv /builds/slave/test/build/venv 05:17:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:17:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:17:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:17:52 INFO - Using real prefix '/tools/python27' 05:17:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:17:53 INFO - Installing distribute.............................................................................................................................................................................................done. 05:17:56 INFO - Installing pip.................done. 05:17:56 INFO - Return code: 0 05:17:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:56 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 0x10fe67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d65910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, '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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:17:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:17:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:56 INFO - 'HOME': '/Users/cltbld', 05:17:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:56 INFO - 'LOGNAME': 'cltbld', 05:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:56 INFO - 'MOZ_NO_REMOTE': '1', 05:17:56 INFO - 'NO_EM_RESTART': '1', 05:17:56 INFO - 'PAGER': '/bin/cat', 05:17:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:56 INFO - 'PWD': '/builds/slave/test', 05:17:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:56 INFO - 'SHELL': '/bin/bash', 05:17:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:17:56 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:17:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:56 INFO - 'USER': 'cltbld', 05:17:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:56 INFO - 'XPC_FLAGS': '0x0', 05:17:56 INFO - 'XPC_SERVICE_NAME': '0', 05:17:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:17:56 INFO - Cleaning up... 05:17:56 INFO - Return code: 0 05:17:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:17:56 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 0x10fe67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d65910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, '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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:17:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:17:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:17:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:17:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:17:56 INFO - 'HOME': '/Users/cltbld', 05:17:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:17:56 INFO - 'LOGNAME': 'cltbld', 05:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:56 INFO - 'MOZ_NO_REMOTE': '1', 05:17:56 INFO - 'NO_EM_RESTART': '1', 05:17:56 INFO - 'PAGER': '/bin/cat', 05:17:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:17:56 INFO - 'PWD': '/builds/slave/test', 05:17:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:17:56 INFO - 'SHELL': '/bin/bash', 05:17:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:17:56 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:17:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:17:56 INFO - 'USER': 'cltbld', 05:17:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:17:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:17:56 INFO - 'XPC_FLAGS': '0x0', 05:17:56 INFO - 'XPC_SERVICE_NAME': '0', 05:17:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:56 INFO - Downloading/unpacking psutil>=0.7.1 05:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:17:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:17:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:17:59 INFO - Installing collected packages: psutil 05:17:59 INFO - Running setup.py install for psutil 05:18:00 INFO - building 'psutil._psutil_osx' extension 05:18:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:18:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:18:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:18:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:18:00 INFO - building 'psutil._psutil_posix' extension 05:18:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:18:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:18:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:18:00 INFO - ^ 05:18:00 INFO - 1 warning generated. 05:18:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:18:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:00 INFO - Successfully installed psutil 05:18:00 INFO - Cleaning up... 05:18:00 INFO - Return code: 0 05:18:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fe67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d65910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, '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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:18:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:18:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:18:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:00 INFO - 'HOME': '/Users/cltbld', 05:18:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:00 INFO - 'LOGNAME': 'cltbld', 05:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:00 INFO - 'MOZ_NO_REMOTE': '1', 05:18:00 INFO - 'NO_EM_RESTART': '1', 05:18:00 INFO - 'PAGER': '/bin/cat', 05:18:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:00 INFO - 'PWD': '/builds/slave/test', 05:18:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:00 INFO - 'SHELL': '/bin/bash', 05:18:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:18:00 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:18:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:00 INFO - 'USER': 'cltbld', 05:18:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:00 INFO - 'XPC_FLAGS': '0x0', 05:18:00 INFO - 'XPC_SERVICE_NAME': '0', 05:18:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:18:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:18:03 INFO - Installing collected packages: mozsystemmonitor 05:18:03 INFO - Running setup.py install for mozsystemmonitor 05:18:03 INFO - Successfully installed mozsystemmonitor 05:18:03 INFO - Cleaning up... 05:18:03 INFO - Return code: 0 05:18:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fe67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d65910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, '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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:03 INFO - 'HOME': '/Users/cltbld', 05:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:03 INFO - 'LOGNAME': 'cltbld', 05:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:03 INFO - 'MOZ_NO_REMOTE': '1', 05:18:03 INFO - 'NO_EM_RESTART': '1', 05:18:03 INFO - 'PAGER': '/bin/cat', 05:18:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:03 INFO - 'PWD': '/builds/slave/test', 05:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:03 INFO - 'SHELL': '/bin/bash', 05:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:18:03 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:03 INFO - 'USER': 'cltbld', 05:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:03 INFO - 'XPC_FLAGS': '0x0', 05:18:03 INFO - 'XPC_SERVICE_NAME': '0', 05:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:18:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18:07 INFO - Downloading docopt-0.6.1.tar.gz 05:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:18:07 INFO - Installing collected packages: blobuploader, requests, docopt 05:18:07 INFO - Running setup.py install for blobuploader 05:18:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:18:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:18:07 INFO - Running setup.py install for requests 05:18:08 INFO - Running setup.py install for docopt 05:18:08 INFO - Successfully installed blobuploader requests docopt 05:18:08 INFO - Cleaning up... 05:18:08 INFO - Return code: 0 05:18:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:08 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 0x10fe67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d65910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, '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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:08 INFO - 'HOME': '/Users/cltbld', 05:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:08 INFO - 'LOGNAME': 'cltbld', 05:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:08 INFO - 'MOZ_NO_REMOTE': '1', 05:18:08 INFO - 'NO_EM_RESTART': '1', 05:18:08 INFO - 'PAGER': '/bin/cat', 05:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:08 INFO - 'PWD': '/builds/slave/test', 05:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:08 INFO - 'SHELL': '/bin/bash', 05:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:18:08 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:08 INFO - 'USER': 'cltbld', 05:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:08 INFO - 'XPC_FLAGS': '0x0', 05:18:08 INFO - 'XPC_SERVICE_NAME': '0', 05:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ietT0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-2SErsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Tk_xYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Mz8mZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-I6RgzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-_hrUtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-b32Sh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-KQjmSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-gLSAk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-hIQUB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-G29Qgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-fQB1pQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-1Rm7Zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-E5Mi3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-nryIyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Y5Xw81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ZQbZT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:18:10 INFO - Running setup.py install for manifestparser 05:18:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:18:10 INFO - Running setup.py install for mozcrash 05:18:10 INFO - Running setup.py install for mozdebug 05:18:11 INFO - Running setup.py install for mozdevice 05:18:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:18:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:18:11 INFO - Running setup.py install for mozfile 05:18:11 INFO - Running setup.py install for mozhttpd 05:18:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:18:11 INFO - Running setup.py install for mozinfo 05:18:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:18:11 INFO - Running setup.py install for mozInstall 05:18:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:18:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:18:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:18:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:18:11 INFO - Running setup.py install for mozleak 05:18:11 INFO - Running setup.py install for mozlog 05:18:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:18:12 INFO - Running setup.py install for moznetwork 05:18:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:18:12 INFO - Running setup.py install for mozprocess 05:18:12 INFO - Running setup.py install for mozprofile 05:18:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:18:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:18:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:18:12 INFO - Running setup.py install for mozrunner 05:18:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:18:12 INFO - Running setup.py install for mozscreenshot 05:18:12 INFO - Running setup.py install for moztest 05:18:13 INFO - Running setup.py install for mozversion 05:18:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:18:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:18:13 INFO - Cleaning up... 05:18:13 INFO - Return code: 0 05:18:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:13 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 0x10fe67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d65910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, '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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:13 INFO - 'HOME': '/Users/cltbld', 05:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:13 INFO - 'LOGNAME': 'cltbld', 05:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:13 INFO - 'MOZ_NO_REMOTE': '1', 05:18:13 INFO - 'NO_EM_RESTART': '1', 05:18:13 INFO - 'PAGER': '/bin/cat', 05:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:13 INFO - 'PWD': '/builds/slave/test', 05:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:13 INFO - 'SHELL': '/bin/bash', 05:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:18:13 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:13 INFO - 'USER': 'cltbld', 05:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:13 INFO - 'XPC_FLAGS': '0x0', 05:18:13 INFO - 'XPC_SERVICE_NAME': '0', 05:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:18:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-OXMQRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:18:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-LadBmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:18:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-yph7EU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:18:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-GZDs7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:18:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-0es4FD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:18:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-qD6wai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:18:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-dGuKLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:18:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-DK8Ux4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:18:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Mu5y7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:18:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-NFW2tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:18:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-t_1SOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:18:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-XyIXMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:18:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-KHsS5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:18:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:18:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-U5wCpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-5lYEyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:18:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-nrPTxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:18:15 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-trQVuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:18:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:17 INFO - Downloading blessings-1.6.tar.gz 05:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:18:17 INFO - Installing collected packages: blessings 05:18:17 INFO - Running setup.py install for blessings 05:18:18 INFO - Successfully installed blessings 05:18:18 INFO - Cleaning up... 05:18:18 INFO - Return code: 0 05:18:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:18:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:18 INFO - Reading from file tmpfile_stdout 05:18:18 INFO - Current package versions: 05:18:18 INFO - blessings == 1.6 05:18:18 INFO - blobuploader == 1.2.4 05:18:18 INFO - docopt == 0.6.1 05:18:18 INFO - manifestparser == 1.1 05:18:18 INFO - mozInstall == 1.12 05:18:18 INFO - mozcrash == 0.17 05:18:18 INFO - mozdebug == 0.1 05:18:18 INFO - mozdevice == 0.48 05:18:18 INFO - mozfile == 1.2 05:18:18 INFO - mozhttpd == 0.7 05:18:18 INFO - mozinfo == 0.9 05:18:18 INFO - mozleak == 0.1 05:18:18 INFO - mozlog == 3.1 05:18:18 INFO - moznetwork == 0.27 05:18:18 INFO - mozprocess == 0.22 05:18:18 INFO - mozprofile == 0.28 05:18:18 INFO - mozrunner == 6.11 05:18:18 INFO - mozscreenshot == 0.1 05:18:18 INFO - mozsystemmonitor == 0.0 05:18:18 INFO - moztest == 0.7 05:18:18 INFO - mozversion == 1.4 05:18:18 INFO - psutil == 3.1.1 05:18:18 INFO - requests == 1.2.3 05:18:18 INFO - wsgiref == 0.1.2 05:18:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:18 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 0x10fe67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d65910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, '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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:18:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:18:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:18 INFO - 'HOME': '/Users/cltbld', 05:18:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:18 INFO - 'LOGNAME': 'cltbld', 05:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:18 INFO - 'MOZ_NO_REMOTE': '1', 05:18:18 INFO - 'NO_EM_RESTART': '1', 05:18:18 INFO - 'PAGER': '/bin/cat', 05:18:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:18 INFO - 'PWD': '/builds/slave/test', 05:18:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:18 INFO - 'SHELL': '/bin/bash', 05:18:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:18:18 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:18:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:18 INFO - 'USER': 'cltbld', 05:18:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:18 INFO - 'XPC_FLAGS': '0x0', 05:18:18 INFO - 'XPC_SERVICE_NAME': '0', 05:18:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:18:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:18:18 INFO - Cleaning up... 05:18:18 INFO - Return code: 0 05:18:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:18 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 0x10fe67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d65910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, '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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:18:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:18:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:18 INFO - 'HOME': '/Users/cltbld', 05:18:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:18 INFO - 'LOGNAME': 'cltbld', 05:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:18 INFO - 'MOZ_NO_REMOTE': '1', 05:18:18 INFO - 'NO_EM_RESTART': '1', 05:18:18 INFO - 'PAGER': '/bin/cat', 05:18:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:18 INFO - 'PWD': '/builds/slave/test', 05:18:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:18 INFO - 'SHELL': '/bin/bash', 05:18:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:18:18 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:18:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:18 INFO - 'USER': 'cltbld', 05:18:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:18 INFO - 'XPC_FLAGS': '0x0', 05:18:18 INFO - 'XPC_SERVICE_NAME': '0', 05:18:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:18 INFO - Downloading/unpacking jsonschema 05:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:18:21 INFO - Downloading/unpacking functools32 (from jsonschema) 05:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:21 INFO - Downloading functools32-3.2.3-2.tar.gz 05:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:18:21 INFO - warning: no files found matching '*.txt' 05:18:21 INFO - no previously-included directories found matching 'build' 05:18:21 INFO - no previously-included directories found matching 'dist' 05:18:21 INFO - no previously-included directories found matching '.git*' 05:18:21 INFO - Installing collected packages: jsonschema, functools32 05:18:21 INFO - Running setup.py install for functools32 05:18:22 INFO - warning: no files found matching '*.txt' 05:18:22 INFO - no previously-included directories found matching 'build' 05:18:22 INFO - no previously-included directories found matching 'dist' 05:18:22 INFO - no previously-included directories found matching '.git*' 05:18:22 INFO - Successfully installed jsonschema functools32 05:18:22 INFO - Cleaning up... 05:18:22 INFO - Return code: 0 05:18:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe67a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe77420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9941d65910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, '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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:22 INFO - 'HOME': '/Users/cltbld', 05:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:22 INFO - 'LOGNAME': 'cltbld', 05:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:22 INFO - 'MOZ_NO_REMOTE': '1', 05:18:22 INFO - 'NO_EM_RESTART': '1', 05:18:22 INFO - 'PAGER': '/bin/cat', 05:18:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:22 INFO - 'PWD': '/builds/slave/test', 05:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:22 INFO - 'SHELL': '/bin/bash', 05:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:18:22 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:22 INFO - 'USER': 'cltbld', 05:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:22 INFO - 'XPC_FLAGS': '0x0', 05:18:22 INFO - 'XPC_SERVICE_NAME': '0', 05:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:22 INFO - Downloading/unpacking flake8 05:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:18:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:18:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:18:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:26 INFO - no previously-included directories found matching 'docs/_build' 05:18:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:18:26 INFO - Running setup.py install for pep8 05:18:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:18:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:18:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:18:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:18:26 INFO - no previously-included directories found matching 'docs/_build' 05:18:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:18:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:18:26 INFO - Cleaning up... 05:18:26 INFO - Return code: 0 05:18:26 INFO - Running post-action listener: _resource_record_post_action 05:18:26 INFO - Running post-action listener: _start_resource_monitoring 05:18:26 INFO - Starting resource monitoring. 05:18:26 INFO - ##### 05:18:26 INFO - ##### Running install step. 05:18:26 INFO - ##### 05:18:26 INFO - Running pre-action listener: _resource_record_pre_action 05:18:26 INFO - Running main action method: install 05:18:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:18:26 INFO - Reading from file tmpfile_stdout 05:18:26 INFO - Detecting whether we're running mozinstall >=1.0... 05:18:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:18:26 INFO - Reading from file tmpfile_stdout 05:18:26 INFO - Output received: 05:18:26 INFO - Usage: mozinstall [options] installer 05:18:26 INFO - Options: 05:18:26 INFO - -h, --help show this help message and exit 05:18:26 INFO - -d DEST, --destination=DEST 05:18:26 INFO - Directory to install application into. [default: 05:18:26 INFO - "/builds/slave/test"] 05:18:26 INFO - --app=APP Application being installed. [default: firefox] 05:18:26 INFO - mkdir: /builds/slave/test/build/application 05:18:26 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'] 05:18:26 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 05:19:00 INFO - Reading from file tmpfile_stdout 05:19:00 INFO - Output received: 05:19:00 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:19:00 INFO - Running post-action listener: _resource_record_post_action 05:19:00 INFO - ##### 05:19:00 INFO - ##### Running run-tests step. 05:19:00 INFO - ##### 05:19:00 INFO - Running pre-action listener: _resource_record_pre_action 05:19:00 INFO - Running main action method: run_tests 05:19:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:00 INFO - 2016-04-15 05:19:00.577 screenresolution[2219:18134] starting screenresolution argv=screenresolution get 05:19:00 INFO - 2016-04-15 05:19:00.580 screenresolution[2219:18134] Display 0: 1600x1200x32@60 05:19:00 INFO - 2016-04-15 05:19:00.586 screenresolution[2220:18138] starting screenresolution argv=screenresolution list 05:19:00 INFO - Available Modes on Display 0 05:19:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:00 INFO - Intel Iris: 05:19:00 INFO - Chipset Model: Intel Iris 05:19:00 INFO - Type: GPU 05:19:00 INFO - Bus: Built-In 05:19:00 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:00 INFO - Vendor: Intel (0x8086) 05:19:00 INFO - Device ID: 0x0a2e 05:19:00 INFO - Revision ID: 0x0009 05:19:00 INFO - Displays: 05:19:00 INFO - Display: 05:19:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:00 INFO - Display Serial Number: 1600x1200 60 05:19:00 INFO - Main Display: Yes 05:19:00 INFO - Mirror: Off 05:19:00 INFO - Online: Yes 05:19:00 INFO - Rotation: Supported 05:19:00 INFO - Return code: 0 05:19:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:00 INFO - Python 2.7.3 05:19:00 INFO - Return code: 0 05:19:00 INFO - grabbing minidump binary from tooltool 05:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:00 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 0x10fd06e00>, '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 0x10fb2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd01b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:19:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:19:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:00 INFO - Return code: 0 05:19:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:19:02 INFO - Return code: 0 05:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0131 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FuowKzF5zz/Render', 05:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:02 INFO - 'HOME': '/Users/cltbld', 05:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:02 INFO - 'LOGNAME': 'cltbld', 05:19:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:02 INFO - 'MOZ_NO_REMOTE': '1', 05:19:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:02 INFO - 'NO_EM_RESTART': '1', 05:19:02 INFO - 'PAGER': '/bin/cat', 05:19:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:02 INFO - 'PWD': '/builds/slave/test', 05:19:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:02 INFO - 'SHELL': '/bin/bash', 05:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YS1gnOZqWJ/Listeners', 05:19:02 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 05:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:02 INFO - 'USER': 'cltbld', 05:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:02 INFO - 'XPC_FLAGS': '0x0', 05:19:02 INFO - 'XPC_SERVICE_NAME': '0', 05:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:19:02 INFO - mozversion application_buildid: 20160415030231 05:19:02 INFO - mozversion application_changeset: afd82f887093e5e9e4015115ca5795ec82a6f732 05:19:02 INFO - mozversion application_display_name: Nightly 05:19:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:02 INFO - mozversion application_name: Firefox 05:19:02 INFO - mozversion application_remotingname: firefox 05:19:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:19:02 INFO - mozversion application_vendor: Mozilla 05:19:02 INFO - mozversion application_version: 48.0a1 05:19:02 INFO - mozversion platform_buildid: 20160415030231 05:19:02 INFO - mozversion platform_changeset: afd82f887093e5e9e4015115ca5795ec82a6f732 05:19:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:19:02 INFO - mozversion platform_version: 48.0a1 05:19:02 INFO - using testdate: 1460722742 05:19:02 INFO - actual date: 1460722742 05:19:02 INFO - starting webserver on 'localhost:52804' 05:19:02 INFO - SUITE-START | Running 2 tests 05:19:02 INFO - TEST-START | dromaeo_css 05:19:02 INFO - operating with platform_type : mac_ 05:19:02 INFO - Initialising browser for dromaeo_css test... 05:19:02 INFO - TEST-INFO | started process 2238 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpV3qvYB/profile http://localhost:52804/getInfo.html) 05:19:04 INFO - PROCESS | 2238 | __metrics Screen width/height:1600/1200 05:19:04 INFO - PROCESS | 2238 | colorDepth:24 05:19:04 INFO - PROCESS | 2238 | Browser inner width/height: 1024/689 05:19:04 INFO - PROCESS | 2238 | __metrics 05:19:04 INFO - PROCESS | 2238 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:19:04 ERROR - PROCESS | 2238 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:19:05 INFO - TEST-INFO | 2238: exit 0 05:19:05 INFO - Browser initialized. 05:19:05 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:05 INFO - TEST-INFO | started process 2241 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpV3qvYB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:17 INFO - PROCESS | 2241 | RSS: Main: 238792704 05:19:17 INFO - PROCESS | 2241 | 05:20:08 INFO - PROCESS | 2241 | 1460722808237 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:22:19 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:52804/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52804/tests/dromaeo/cssquery-ext.html) 05:22:21 INFO - PROCESS | 2241 | RSS: Main: 362405888 05:22:21 INFO - PROCESS | 2241 | 05:25:24 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:52804/tests/dromaeo/cssquery-ext.html (next: http://localhost:52804/tests/dromaeo/cssquery-jquery.html) 05:25:24 INFO - PROCESS | 2241 | RSS: Main: 378785792 05:25:24 INFO - PROCESS | 2241 | 05:28:26 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:52804/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52804/tests/dromaeo/cssquery-mootools.html) 05:28:27 INFO - PROCESS | 2241 | RSS: Main: 376381440 05:28:27 INFO - PROCESS | 2241 | 05:31:29 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:52804/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52804/tests/dromaeo/cssquery-prototype.html) 05:31:30 INFO - PROCESS | 2241 | RSS: Main: 376688640 05:31:30 INFO - PROCESS | 2241 | 05:34:33 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:52804/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52804/tests/dromaeo/cssquery-yui.html) 05:34:33 INFO - PROCESS | 2241 | RSS: Main: 346529792 05:34:33 INFO - PROCESS | 2241 | 05:37:36 INFO - PROCESS | 2241 | Cycle 1(1): loaded http://localhost:52804/tests/dromaeo/cssquery-yui.html (next: http://localhost:52804/tests/dromaeo/cssquery-dojo.html) 05:37:36 INFO - PROCESS | 2241 | RSS: Main: 409620480 05:37:36 INFO - PROCESS | 2241 | 05:37:36 INFO - PROCESS | 2241 | __start_tp_report 05:37:36 INFO - PROCESS | 2241 | _x_x_mozilla_page_load 05:37:36 INFO - PROCESS | 2241 | _x_x_mozilla_page_load_details 05:37:36 INFO - PROCESS | 2241 | |i|pagename|runs| 05:37:36 INFO - PROCESS | 2241 | |0;dojo.html;3377.341418423407;3439.4840773883916;3477.269773347597;3481.0427132538557;3485.5294535237736;5617.38208797033;5700.258966334376;5702.344230413503;5706.5720070994685;5723.141528770687;4752.809887604491;5667;5781.739821708023;5797.507211886995;5828.300603927524;5139;5169.276301317809;5184.53339199472;5202.583793296546;5290.470952904698;5299.973500132494;5307.044731948247;5322.2282768998475;5344.4922732340365;5363.8122665706705;5142.357205349332;5241.764120614582;5244.213367994812;5248.737563121828;5253.212018197263;5143.922841157386;5156.3296771419855;5157.406898206679;5158.896822063575;5169.896602067937;5111.6932984020705;5119.0529751996;5153.433122356538;5154.278401023859;5264.447233040514;5593.021221286259;5656.321241451053;5673.801416950429;5684.8294551163535;5686.9763442580315;6178.196834411543;6200.3799620037635;6266.185395898549;6288.52836037299;6295.18162638854;5288.206768984644;6082;6237.939550276464;6271.905921411182;6276.497880169575;5771.769129234825;5827.3007239131575;5850.5904586678525;5881.9705901470215;5884.617499862496;6247.094171345129;6253.7185826637915;6278.340774218733;6282.874342513215;6297.464715499143;5308.256844041837;5932.822015339547;6083.939160608337;6186.783462578788;6188.44304012641;6149.938500614937;6181.536384771161;6193.907091393633;6205.6586887721605;6209.875802484015;6139.369480960552;6146.063989843275;6147.047207682816;6247.312795592481;6252.124702541648;6208.1308616793685;6233.75064997395;6240.032794916795;6260.843478913063;6288.748450061947;4953.455119936804;4955.752212389366;4956.752162391866;4966.453690094087;5002.924956125661;4343.066240758252;4388.758618275961;4450.287953927356;4459.576340247736;4493.213687604656;5720.942790572124;5743.052396354638;5750.769969201268;5755.769769209269;5756.884862302731;6182.629042257479;6241.750329986749;6245.5628106032145;6258.280297765724;6282.340354262828;5596.132599447093;5695.202671625976;5700.458456446631;5713.542916566747;5721.5136713379;5365.248865158881;5398.055340315507;5399.919001214985;5402.648827826258;5404.91892621611;6247.562670613013;6253.6873156342;6255.093011513214;6256.092866534327;6261.342559031418;6207.782727604512;6213.440790328892;6243.843903902439;6248.343923887926;6273.090401891792;6278.968605156945;6282.403171698681;6291.716872740646;6298.08677741734;6307.243130824147;6055.303640081271;6142.406692085002;6179.134921111138;6188.752449901954;6193.876122477435;6179.5056395489155;6270.341614130451;6276.152719383008;6293.087502312231;6314.147590075281;5299.549538289289;5459.181122831607;6073.210482637229;6101.84745381369;6110.969445152746;5896.970515147397;6017.428344307284;6071.150038994456;6095.664738439428;6105.17580126695;5892.234009578728;5974.9402505974385;5978.133170690313;5981.401859813984;6021.698915054318;6086.178365920721;6086.817395478142;6104.664243466652;6107.340840728934;6124.0201567748945;5828.970855145867;5952.0179170436395;5964.522838173022;6001.189839371629;6014.81955541317 05:37:36 INFO - PROCESS | 2241 | |1;ext.html;494.13033061811245;538.4911258860376;575.4705670782879;610.3987572241344;651.6640886183322;28970.420591588154;37138.81430592846;38329.61670383299;38386;38511.61488385115;32978.67021329786;43152.352714709305;43242.56757432425;43297.83510824462;43500.78249608755;8445.451045682043;9412.199963003153;9642.614295428175;9672.032796720307;9673.758156046084;8539.829203415928;11504.252223605481;11818.468168932375;11827.231229970068;11841.644750657451;11452.599159029436;11587.362695051768;11590.420478976059;11592.942035289854;11687.415629218547;8520.40357174998;8757.781055473664;11446.484908179153;11577.536898524007;11615.954564089272;8566.785830354229;8675.305975522006;11371.147163962736;11731.94134029333;11732;38036.429453558216;38498;38589.228215435534;38644.80677596622;38685;3089.938201235963;3233.4664780311455;3374.780639258465;3411.7441191910457;3481.9129521761906;24622.8919698614;25236.747632523806;27184.728152718417;27270.318242043908;27426.862865685744;2674.41162944152;2678.076063758;2709.878055487508;2720.7143249958667;2794.888204471819;293057;295615;296016;296584;296793;60018;60550;60635.39364606386;60638.69680651568;60720.39279607147;52681.736591316796;63604;63796.68101659555;63800.36199638037;63852;16106.516804495885;16564.503064908073;16567;16574.585635359213;16662.333506659597;11593.362365070003;12679.619611411672;12926.935365323114;12985.480580776662;12986.415611297514;15867.285972131282;15901.840981590036;15938.28277727505;16034;16246.350145994027;5655.349634791969;5802.970985145132;6840.486963477759;6847.897281540781;6915.930840691529;893.5085702863399;936.1400920188753;945.9290553208536;997.7156510394502;1044.9289478284818;28936.276593085342;29184.854075729487;29431.705682943324;30051.088249702254;30066.84966575153;1073.1629329123296;1087.907527860125;1096.6655170173015;1146.7075895646556;1164.2548768787983;14975.32611032506;15120.697586048438;15159.545213643609;15423.151726654918;15473.767893481607;594525;600266;601027;601966;602938;58738.41261587329;59124;59155;59258.40741592615;59356.81286374248;1190.7618476304601;1327.1042046618493;1466.222901861972;1487.263804416799;1487.5537338798317;20542.897285513478;25808.38622619866;28484.287892802018;28544.858205671542;28824.711752883042;36499.81750091232;36958.8152059238;45585.088298234514;46031;46128.769356153;345660;349328.5335733254;349354;349583;349645;32725.34549309048;37725;38243.617563825115;39359.60640393674;40032;41909.371359430246;42163.78918105512;42772.358414624425;42831.57168428276;43269.35095973626;34024.17443987847;35072.64927350694;35230;35954;36037.09907252339;35689.75085871982;35694.464583030756;35827.641723583474;36534.63465365312;36656.633433666386;35684.821575891954;35916.4612530807;36313.63686363103;36504.81747591245;36564.81717591395;30378.69621303847;31592.842035790592;31633.841830790698;31676.732930682516;31692.683073168973;17811.643767124846;23846;24102.638460422757;24328.75671243265;24400.87799560991 05:37:36 INFO - PROCESS | 2241 | |2;jquery.html;393.0998014546688;393.201800345299;393.9192465544563;394.12110992486737;407.079999201804;19250.61498770024;19612.90193549032;20165.59668806623;20588.97055147244;20615.38153855383;18897;19460.902695486537;20202.090905909234;20934.685979710317;20991.790082099174;5669.574781891354;5983.341832398432;6031.155638210654;6037.909431358533;6040.969795151018;5940.732667029973;5943.970280148615;6016.6390016598925;6059.909101363483;6073.787417440369;5823.708814559276;5983.730732117044;6011.849703757397;6017.669028203447;6024.548158888079;4573.839915602971;5932.317783454915;6001.339852616209;6007.098935159717;6012.368701286389;5693.3168019837885;5722.399148089474;5728.541716662657;5730.914036289459;5731.828045158652;13406.798898016537;13473.528426505025;13489.527866524624;13530.594082177451;20896.47758806027;2100.2964007057653;2308.3651995701316;2366;2409.771071748181;2588.2105957682897;9704.175145112675;10206.183505319557;10348.379097254188;12471.75056498874;13283;1688.696034713751;1737.7132773092424;1791.2476759760932;1843.741876137342;1883.5026154207437;114909;120679;121012.39493802562;122768.77231227663;124595;11617.709557260967;11643;11678.70803229926;11681.182317237712;11846.407679616157;14916.477923272634;14978.325975331136;15028.323725432381;15043.323050462537;15043.924780376246;7111.92888071123;8663.783405414915;8695.695650652196;8709.216170544554;8819.955900220459;8525.70160044408;8528.957355213184;8555.657773688983;8629.39594228398;8666.220040196287;4572.4055872736335;4590.173768721662;4612.976935115303;4621.907561848744;4643.096330504477;1613.322404590075;1991.6614175589896;1997.8701384409967;2001.9699704504444;2022.6157030164516;894.6510860764307;931.7810314576069;970.1898914406408;972.4700038479045;980.127686359141;16751.58121046959;19033.238670453306;19170.329038483585;19922.90038549798;20678.966051697356;934.7289286107089;956.416585882611;969.4038166527534;972.6255391674115;1033.8035773203067;10590.417527035934;10743.946280268548;10794;10830;10973.615923442641;509671.4516427394;517898;518297;602442;603275;132569.33715331362;137886;138751.999999998;140865;143435;1020.3622735790132;1044.7597052677927;1062.2670357453333;1064.2869277584036;1065.1851333729703;15124.924375378052;17686.02726849984;17727.29090836352;17859.642807143526;18015.099245037432;49761.50238497568;51571.742141289054;52282.47717522928;52520.47479525156;52632;76900;77912;78484.4303113946;79070.418591629;81041.18958810573;7007.509474336747;7550.584717840571;7933.294341716559;8004.5597492138495;8167.877481837659;11805.409729513662;11954;12188.51245950152;12286.75426491483;12367.75264494723;8894.777630559287;10482.737931551772;10495.947520262349;10498.895011050097;10952.726181845199;9148.405353651991;10179.185665146622;10324.48377581133;10433.32183408076;10737.409442480739;10744.449716554578;10929.453527323443;11266.436678166223;11273.492692829006;11423.200375973602;7733.651985660546;7979.122296547491;7994.200579942192;8008.239217274349;8294.046184688837;10074.496275186064;10621.89378106209;10891.237613366988;10933.671989840317;10953.233273671085 05:37:36 INFO - PROCESS | 2241 | |3;mootools.html;1382.6888949986248;1582.5806161367236;1592.9601759956004;1802.999999999999;1937.67059599868;7790.831375293708;8457.492550446977;8701.521416322099;8709.956450217747;8740.956295218515;8336.874946875801;8553.016403113645;8559.657613695445;8566.828663426728;8569.185927336908;2140.7324084489437;2350.4006478347997;2399.9999999999955;2440.4143005678598;2447.204658485999;2448.3634255093716;2468.395243165427;2482.950340993179;2487.2786891801447;2491.1032028469735;2075.785665385751;2414.8309618326725;2444.1201167579657;2449.2040086971715;2455.4966231922413;2115.185653523397;2326.8371214015024;2407.723111842143;2413.722421921484;2415.384077060347;2415.577273977047;2437.756224377566;2439.890204940782;2440.7803297703113;2443.438009257881;8621;8660.610272537751;8668.39321247513;8669.826603467895;8685.435446695945;4142.5857414258635;5204.245384419276;5283.181106928394;5294.470552944713;5302.310699609065;2871.3539453622975;3475.826208689555;3514.8242587870504;3604.1530240393486;3604.441311596707;1008.0071129937079;1015.3755440404173;1017.2675673515057;1029.1663752360612;1047.1570385839395;11134;11393.601223957205;11433;11488.88511114895;11537.711557211052;4472.664550158751;4557.703749256288;4646.83736069236;4691.39011928454;4692.648051396159;8966.803133232399;9037.367384283038;9055.95472022649;9059.592318345558;9066.773330666787;5229.869253268699;5235.738213089407;5240.842774716764;5248.05535003703;5279.736013199325;4359.891002724958;4518.390017347616;4704.223803072455;4860.343853579792;4886.193778026657;3728.515293011891;3763.3226019316785;3840.116773142137;3852.845886164578;3856.382978723391;1039.8284283093205;1192.362086283834;1198.4547031100828;1209.9395030248452;1211.2611307102661;823.2714048067503;841.7643059943226;852.1308265569085;906.9138431848963;943.1888575824922;5808.419158084243;5826.912596311059;5898.439648233497;6052.788152414732;6105.931461994222;907.1291560102376;963.6001059560203;965.6571916969515;985.640241311916;1010.1565193063874;3634.8727794527063;3917.745346552465;3986.402039694069;3991.2416640838146;3993.7603743775467;9552.522373881417;9604.94345621981;9941.751456213653;10620.468976551141;10761.354318740743;8956.462612243286;10234.846477302846;10530.21023423231;10687.572497100184;10695.304805187638;7788.4548081634875;8091.19088091198;8118;8123.75628731139;8143.552104634184;5557.888842223214;5716.5998380113;5730.541556675539;5814.273215848019;5835.824925252249;9763.218942484726;9793.706188814347;9808.950955245178;9879.160271377013;9952.10431061179;9570.712878613653;9754.853677194706;9886.4562449066;9994.750131246778;10142.340747851365;6382.776602818879;6637.303083176197;6654;6806.625635589892;6949.30506949301;8218.260356567938;8223.95888020556;8302.169783021649;8309.501429914224;8319.584020798815;7181.533200341961;7368.557886526611;7388.519746216479;7410.703571857065;7557.962210188914;7167.1757747859665;7212.945600301589;7239.674214660247;7306.890396643948;7363.15323737777;6901.206361268318;6916.515843890879;6971.51199416036;6976.19773726028;6991;4797.712137271775;4845.903081938271;4888.6577939543895;4944.480829512993;5073.720945347894;4868.736037742467;4894.510548945077;4941.950580494149;4993.27597498368;5158.535731784157 05:37:36 INFO - PROCESS | 2241 | |4;prototype.html;309.75065072616536;320.23783395518666;328.33512137920724;328.61880218946027;329.8779451602907;4683.672142949995;4924.606031517479;5892.909811684841;5945.227120474337;5986.790462333819;4984.975075124619;5350.250964864922;5675.3757086720425;5831.30024397073;5881.265211676569;4260;4495.775211239433;4501.707389019703;4510.233260345749;4521.186186486431;3496.0283758805067;4365.607095361417;4490.550944905516;4537.70504917181;4547.272036626077;3934.980325098385;4211.7262377945335;4323.783810809463;4356.063446359032;4395.274779661368;4400.361947517608;4405.0969551241915;4408.801603927831;4440.467143942716;4448.932256258507;3559.875404360835;3779.1496913194587;3779.508663873679;3785.470034195215;3808.257389808988;5273.894522109575;5645.830625081213;5715.685637289947;5719.7140142992685;5720.771169153229;4303.902504861272;4765.380500534909;4884.511548845087;5068.518490743373;5069.986002799418;5326.653767505139;5851.707414629252;5854.882902341972;6167.629942203437;6232.875342493125;4062.6749860011128;4070.857519986816;4292.205941900758;4322.351647252905;4332.7400355978425;5577.52591029763;6206.565540412173;6401.423871851509;6406.295307516216;6521.250056243545;4192.853250136231;4400.44994375703;4418.8453404130705;4419.607823535576;4422.270325396274;4172.436721042621;4267.509236437787;4476.2837945928495;4493.236149854532;4496.437945256843;4214.946263434142;4548.408706868086;5148.33071700684;5191.273221748958;5194.818181363634;6244.815144276361;6248.4069111318295;6252.531060170505;6304.275008374004;6304.401081897213;1309.6005718256024;1504.428317239464;1536.3393740691386;1536.377767004365;1545.389571119424;965.6909788867495;992.8709267795141;1012.6810054832704;1023.2888142740724;1032.1742605915238;5784.82645520635;5815.87720482021;5836.9124463133085;5843.298804143445;5850.180974663551;5622.91565626516;5628.802991895264;5776.971115144482;6348.904766428507;6446.129772480655;5114.71869047206;5160.8967820643375;5602.803901863415;5825.592208545361;5829.504492118217;3369.5451114099774;3394.2193295542165;3408.2672225471642;3409.3522230776075;3412.5734283214597;5633.57748168889;6430.967845160744;6715.2613212546585;6744.966275168691;6878.55289406187;5645.435456454322;6682.532222744361;6705.631190284484;6731.730730770812;6745.662716864137;6319.431251187415;6799.558028728117;6830.282820303896;6845.418139458102;6859.124594604033;5931.822045338647;6296.496280297657;6417.294097649162;6438.259600146043;6498.675066246763;5400.702961337164;6318.515148940088;6374.012028135647;6378.77674281398;6414.524659328287;6345.809625711236;6788;6805.332693490127;6810.761623343159;6814.659267036727;4122.587741225853;4190.350495673176;4203.327467605169;4210.7473551587;4219.53585105632;5358.651687640291;5743.4543718346695;6114.7554097837365;6466.773662921775;6488.6755662215755;6169.167162433194;6230.719617617127;6250.249970003629;6259.780907668392;6286.308506064421;5275.577953763766;6218.9378106218355;6244;6250.218722659668;6250.593711408744;5812.94187058141;5839.416058394127;6121.663308517896;6124.938750612437;6130.785422510191;5015.398152221756;5477.260569823023;6134.8159555213415;6231.937680623317;6266.342034086355;5929.347771745191;6113.786017489367;6128.571000029955;6142.846428839315;6149.292831324455 05:37:37 INFO - PROCESS | 2241 | |5;yui.html;691.619609214932;698.8078278473422;741.6254791330377;765.6171914042978;769.8729709597915;15405.537833864973;15565.92217038916;15759.290831912564;15760.842391576081;15762.133082680448;13926.234057126854;14023.509177178801;14068.437262509513;14079.648008799759;14088.577342679737;618.5824568416324;756.6179079564812;904.0913881549034;920.1626519866923;924.191332583989;871.7602659268689;907.2787134228304;907.9682211122612;909.4088842252555;910.927125829932;950.8240975419535;969.9854502182473;975.551246426641;976.3458482816494;978.7895602445473;759.5025258455693;916.2303664921432;947.0576776107816;961.7403301108696;964.0118878149882;705.3158436316764;705.4427002667886;706.3466293678327;707.1302298173275;707.2291202589203;15101.622459438491;15232.314545845464;15238.390464381411;15240.923795381063;15245.61885952849;10754.462276886125;12707.428165732565;12999.545015924397;13010.674733131653;13279.402426890812;10090.445025523593;10102.797944041151;10201.897981020169;10554.947225263824;10651.094656954168;2980.970190298091;3511.1924257420683;3517.3317069756663;3587.228745819635;3632.3098611263777;79857.60071199665;80195;82028;82172.17827821705;83408.58295708483;9535.332526723063;9551.761205969906;9731.221502279801;9786.266030047633;10122.949385253027;22108.447288817588;22113.447163821034;22121.004554795072;22135.114595416326;22146.677998300383;7013.123359580052;7224.927750722425;7434.36807871337;7480.855782727483;7558.659860306299;3439.6904278615043;4091.7135800494275;4114.588541145861;4176.2900306947895;4213.4522512073845;3001.834899080528;3455.550778398792;3562.8752993645617;3570.4822800693755;3585.8207089645935;526.8050821196182;603.0953569645532;604.332212904734;616.5468380740152;628.7579281976472;460.3233247126718;493.3339991012105;536.3724442402398;566.4788394676907;575.3900865083025;7510.248975102555;7525.698972041167;7558.58427786466;7604.961975190199;7707.344875685518;533.9759710813023;556.115775916295;557.3868744381148;572.7880684146809;576.0466428061482;3566.4115420955773;3585.4801053847045;3662.212624285791;3690.450784890803;3771.849126034983;86801.69797453043;87608.56195718981;88695;88852;89866.10133898708;78317;79585.20414795894;79629.20370796334;79677;81397.59301203575;69497;69589;69825;70811;70878.2912170882;7039.964800175966;7057.082579264663;7170.569765814068;7267.963660181666;7278.417726581755;29649.5552566716;41857.34925785102;42164;42503.724888253404;44191.776755231534;54819.90360192694;57613.42386576248;62129.37870621236;64020;64084;9792.80414391694;9980.101790838859;10024.799504010025;10586.629467968583;10925.344479331265;11709.355985420883;11763.588274410697;12217.0226381891;12235.449404776627;12549.623511294676;10680.359178449318;10789.190810689073;10956.013958743433;11101.611443599435;11201.43992800373;11114.555417783198;11249.100071994384;11350.829737554108;11400.771984560097;11536.653900383002;11195.216334856483;11204.71988200269;11387.487563059516;11517;12185.451654675739;9145.451272923647;9168.724938251864;9287.349885507949;9564.4739539326;9578.56896439648;8922.375433719577;8923.643054277756;8997.82004359896;9197.862032069657;9825.133224687572 05:37:37 INFO - PROCESS | 2241 | __end_tp_report 05:37:37 INFO - PROCESS | 2241 | __start_cc_report 05:37:37 INFO - PROCESS | 2241 | _x_x_mozilla_cycle_collect,1176 05:37:37 INFO - PROCESS | 2241 | __end_cc_report 05:37:37 INFO - PROCESS | 2241 | __startTimestamp1460723856986__endTimestamp 05:37:37 INFO - PROCESS | 2241 | 05:37:37 INFO - PROCESS | 2241 | ------- Summary: start ------- 05:37:37 INFO - PROCESS | 2241 | Number of tests: 6 05:37:37 INFO - PROCESS | 2241 | 05:37:37 INFO - PROCESS | 2241 | [#0] dojo.html Cycles:165 Average:5724.90 Median:5932.82 stddev:626.48 (10.6%) stddev-sans-first:600.72 05:37:37 INFO - PROCESS | 2241 | Values: 3377.3 3439.5 3477.3 3481.0 3485.5 5617.4 5700.3 5702.3 5706.6 5723.1 4752.8 5667.0 5781.7 5797.5 5828.3 5139.0 5169.3 5184.5 5202.6 5290.5 5300.0 5307.0 5322.2 5344.5 5363.8 5142.4 5241.8 5244.2 5248.7 5253.2 5143.9 5156.3 5157.4 5158.9 5169.9 5111.7 5119.1 5153.4 5154.3 5264.4 5593.0 5656.3 5673.8 5684.8 5687.0 6178.2 6200.4 6266.2 6288.5 6295.2 5288.2 6082.0 6237.9 6271.9 6276.5 5771.8 5827.3 5850.6 5882.0 5884.6 6247.1 6253.7 6278.3 6282.9 6297.5 5308.3 5932.8 6083.9 6186.8 6188.4 6149.9 6181.5 6193.9 6205.7 6209.9 6139.4 6146.1 6147.0 6247.3 6252.1 6208.1 6233.8 6240.0 6260.8 6288.7 4953.5 4955.8 4956.8 4966.5 5002.9 4343.1 4388.8 4450.3 4459.6 4493.2 5720.9 5743.1 5750.8 5755.8 5756.9 6182.6 6241.8 6245.6 6258.3 6282.3 5596.1 5695.2 5700.5 5713.5 5721.5 5365.2 5398.1 5399.9 5402.6 5404.9 6247.6 6253.7 6255.1 6256.1 6261.3 6207.8 6213.4 6243.8 6248.3 6273.1 6279.0 6282.4 6291.7 6298.1 6307.2 6055.3 6142.4 6179.1 6188.8 6193.9 6179.5 6270.3 6276.2 6293.1 6314.1 5299.5 5459.2 6073.2 6101.8 6111.0 5897.0 6017.4 6071.2 6095.7 6105.2 5892.2 5974.9 5978.1 5981.4 6021.7 6086.2 6086.8 6104.7 6107.3 6124.0 5829.0 5952.0 5964.5 6001.2 6014.8 05:37:37 INFO - PROCESS | 2241 | 05:37:37 INFO - PROCESS | 2241 | [#1] ext.html Cycles:180 Average:56609.95 Median:176405.22 stddev:116218.56 (65.9%) stddev-sans-first:116468.22 05:37:37 INFO - PROCESS | 2241 | Values: 494.1 538.5 575.5 610.4 651.7 28970.4 37138.8 38329.6 38386.0 38511.6 32978.7 43152.4 43242.6 43297.8 43500.8 8445.5 9412.2 9642.6 9672.0 9673.8 8539.8 11504.3 11818.5 11827.2 11841.6 11452.6 11587.4 11590.4 11592.9 11687.4 8520.4 8757.8 11446.5 11577.5 11616.0 8566.8 8675.3 11371.1 11731.9 11732.0 38036.4 38498.0 38589.2 38644.8 38685.0 3089.9 3233.5 3374.8 3411.7 3481.9 24622.9 25236.7 27184.7 27270.3 27426.9 2674.4 2678.1 2709.9 2720.7 2794.9 293057.0 295615.0 296016.0 296584.0 296793.0 60018.0 60550.0 60635.4 60638.7 60720.4 52681.7 63604.0 63796.7 63800.4 63852.0 16106.5 16564.5 16567.0 16574.6 16662.3 11593.4 12679.6 12926.9 12985.5 12986.4 15867.3 15901.8 15938.3 16034.0 16246.4 5655.3 5803.0 6840.5 6847.9 6915.9 893.5 936.1 945.9 997.7 1044.9 28936.3 29184.9 29431.7 30051.1 30066.8 1073.2 1087.9 1096.7 1146.7 1164.3 14975.3 15120.7 15159.5 15423.2 15473.8 594525.0 600266.0 601027.0 601966.0 602938.0 58738.4 59124.0 59155.0 59258.4 59356.8 1190.8 1327.1 1466.2 1487.3 1487.6 20542.9 25808.4 28484.3 28544.9 28824.7 36499.8 36958.8 45585.1 46031.0 46128.8 345660.0 349328.5 349354.0 349583.0 349645.0 32725.3 37725.0 38243.6 39359.6 40032.0 41909.4 42163.8 42772.4 42831.6 43269.4 34024.2 35072.6 35230.0 35954.0 36037.1 35689.8 35694.5 35827.6 36534.6 36656.6 35684.8 35916.5 36313.6 36504.8 36564.8 30378.7 31592.8 31633.8 31676.7 31692.7 17811.6 23846.0 24102.6 24328.8 24400.9 05:37:37 INFO - PROCESS | 2241 | 05:37:37 INFO - PROCESS | 2241 | [#2] jquery.html Cycles:180 Average:33636.75 Median:20915.58 stddev:93066.45 (445.0%) stddev-sans-first:93294.05 05:37:37 INFO - PROCESS | 2241 | Values: 393.1 393.2 393.9 394.1 407.1 19250.6 19612.9 20165.6 20589.0 20615.4 18897.0 19460.9 20202.1 20934.7 20991.8 5669.6 5983.3 6031.2 6037.9 6041.0 5940.7 5944.0 6016.6 6059.9 6073.8 5823.7 5983.7 6011.8 6017.7 6024.5 4573.8 5932.3 6001.3 6007.1 6012.4 5693.3 5722.4 5728.5 5730.9 5731.8 13406.8 13473.5 13489.5 13530.6 20896.5 2100.3 2308.4 2366.0 2409.8 2588.2 9704.2 10206.2 10348.4 12471.8 13283.0 1688.7 1737.7 1791.2 1843.7 1883.5 114909.0 120679.0 121012.4 122768.8 124595.0 11617.7 11643.0 11678.7 11681.2 11846.4 14916.5 14978.3 15028.3 15043.3 15043.9 7111.9 8663.8 8695.7 8709.2 8820.0 8525.7 8529.0 8555.7 8629.4 8666.2 4572.4 4590.2 4613.0 4621.9 4643.1 1613.3 1991.7 1997.9 2002.0 2022.6 894.7 931.8 970.2 972.5 980.1 16751.6 19033.2 19170.3 19922.9 20679.0 934.7 956.4 969.4 972.6 1033.8 10590.4 10743.9 10794.0 10830.0 10973.6 509671.5 517898.0 518297.0 602442.0 603275.0 132569.3 137886.0 138752.0 140865.0 143435.0 1020.4 1044.8 1062.3 1064.3 1065.2 15124.9 17686.0 17727.3 17859.6 18015.1 49761.5 51571.7 52282.5 52520.5 52632.0 76900.0 77912.0 78484.4 79070.4 81041.2 7007.5 7550.6 7933.3 8004.6 8167.9 11805.4 11954.0 12188.5 12286.8 12367.8 8894.8 10482.7 10495.9 10498.9 10952.7 9148.4 10179.2 10324.5 10433.3 10737.4 10744.4 10929.5 11266.4 11273.5 11423.2 7733.7 7979.1 7994.2 8008.2 8294.0 10074.5 10621.9 10891.2 10933.7 10953.2 05:37:37 INFO - PROCESS | 2241 | 05:37:37 INFO - PROCESS | 2241 | [#3] mootools.html Cycles:180 Average:5573.23 Median:5232.80 stddev:3074.25 (58.7%) stddev-sans-first:3066.74 05:37:37 INFO - PROCESS | 2241 | Values: 1382.7 1582.6 1593.0 1803.0 1937.7 7790.8 8457.5 8701.5 8710.0 8741.0 8336.9 8553.0 8559.7 8566.8 8569.2 2140.7 2350.4 2400.0 2440.4 2447.2 2448.4 2468.4 2483.0 2487.3 2491.1 2075.8 2414.8 2444.1 2449.2 2455.5 2115.2 2326.8 2407.7 2413.7 2415.4 2415.6 2437.8 2439.9 2440.8 2443.4 8621.0 8660.6 8668.4 8669.8 8685.4 4142.6 5204.2 5283.2 5294.5 5302.3 2871.4 3475.8 3514.8 3604.2 3604.4 1008.0 1015.4 1017.3 1029.2 1047.2 11134.0 11393.6 11433.0 11488.9 11537.7 4472.7 4557.7 4646.8 4691.4 4692.6 8966.8 9037.4 9056.0 9059.6 9066.8 5229.9 5235.7 5240.8 5248.1 5279.7 4359.9 4518.4 4704.2 4860.3 4886.2 3728.5 3763.3 3840.1 3852.8 3856.4 1039.8 1192.4 1198.5 1209.9 1211.3 823.3 841.8 852.1 906.9 943.2 5808.4 5826.9 5898.4 6052.8 6105.9 907.1 963.6 965.7 985.6 1010.2 3634.9 3917.7 3986.4 3991.2 3993.8 9552.5 9604.9 9941.8 10620.5 10761.4 8956.5 10234.8 10530.2 10687.6 10695.3 7788.5 8091.2 8118.0 8123.8 8143.6 5557.9 5716.6 5730.5 5814.3 5835.8 9763.2 9793.7 9809.0 9879.2 9952.1 9570.7 9754.9 9886.5 9994.8 10142.3 6382.8 6637.3 6654.0 6806.6 6949.3 8218.3 8224.0 8302.2 8309.5 8319.6 7181.5 7368.6 7388.5 7410.7 7558.0 7167.2 7212.9 7239.7 7306.9 7363.2 6901.2 6916.5 6971.5 6976.2 6991.0 4797.7 4845.9 4888.7 4944.5 5073.7 4868.7 4894.5 4942.0 4993.3 5158.5 05:37:37 INFO - PROCESS | 2241 | 05:37:37 INFO - PROCESS | 2241 | [#4] prototype.html Cycles:180 Average:5047.01 Median:5639.51 stddev:1563.30 (27.7%) stddev-sans-first:1526.72 05:37:37 INFO - PROCESS | 2241 | Values: 309.8 320.2 328.3 328.6 329.9 4683.7 4924.6 5892.9 5945.2 5986.8 4985.0 5350.3 5675.4 5831.3 5881.3 4260.0 4495.8 4501.7 4510.2 4521.2 3496.0 4365.6 4490.6 4537.7 4547.3 3935.0 4211.7 4323.8 4356.1 4395.3 4400.4 4405.1 4408.8 4440.5 4448.9 3559.9 3779.1 3779.5 3785.5 3808.3 5273.9 5645.8 5715.7 5719.7 5720.8 4303.9 4765.4 4884.5 5068.5 5070.0 5326.7 5851.7 5854.9 6167.6 6232.9 4062.7 4070.9 4292.2 4322.4 4332.7 5577.5 6206.6 6401.4 6406.3 6521.3 4192.9 4400.4 4418.8 4419.6 4422.3 4172.4 4267.5 4476.3 4493.2 4496.4 4214.9 4548.4 5148.3 5191.3 5194.8 6244.8 6248.4 6252.5 6304.3 6304.4 1309.6 1504.4 1536.3 1536.4 1545.4 965.7 992.9 1012.7 1023.3 1032.2 5784.8 5815.9 5836.9 5843.3 5850.2 5622.9 5628.8 5777.0 6348.9 6446.1 5114.7 5160.9 5602.8 5825.6 5829.5 3369.5 3394.2 3408.3 3409.4 3412.6 5633.6 6431.0 6715.3 6745.0 6878.6 5645.4 6682.5 6705.6 6731.7 6745.7 6319.4 6799.6 6830.3 6845.4 6859.1 5931.8 6296.5 6417.3 6438.3 6498.7 5400.7 6318.5 6374.0 6378.8 6414.5 6345.8 6788.0 6805.3 6810.8 6814.7 4122.6 4190.4 4203.3 4210.7 4219.5 5358.7 5743.5 6114.8 6466.8 6488.7 6169.2 6230.7 6250.2 6259.8 6286.3 5275.6 6218.9 6244.0 6250.2 6250.6 5812.9 5839.4 6121.7 6124.9 6130.8 5015.4 5477.3 6134.8 6231.9 6266.3 5929.3 6113.8 6128.6 6142.8 6149.3 05:37:37 INFO - PROCESS | 2241 | 05:37:37 INFO - PROCESS | 2241 | [#5] yui.html Cycles:180 Average:17722.13 Median:29094.74 stddev:24950.04 (85.8%) stddev-sans-first:24987.26 05:37:37 INFO - PROCESS | 2241 | Values: 691.6 698.8 741.6 765.6 769.9 15405.5 15565.9 15759.3 15760.8 15762.1 13926.2 14023.5 14068.4 14079.6 14088.6 618.6 756.6 904.1 920.2 924.2 871.8 907.3 908.0 909.4 910.9 950.8 970.0 975.6 976.3 978.8 759.5 916.2 947.1 961.7 964.0 705.3 705.4 706.3 707.1 707.2 15101.6 15232.3 15238.4 15240.9 15245.6 10754.5 12707.4 12999.5 13010.7 13279.4 10090.4 10102.8 10201.9 10554.9 10651.1 2981.0 3511.2 3517.3 3587.2 3632.3 79857.6 80195.0 82028.0 82172.2 83408.6 9535.3 9551.8 9731.2 9786.3 10122.9 22108.4 22113.4 22121.0 22135.1 22146.7 7013.1 7224.9 7434.4 7480.9 7558.7 3439.7 4091.7 4114.6 4176.3 4213.5 3001.8 3455.6 3562.9 3570.5 3585.8 526.8 603.1 604.3 616.5 628.8 460.3 493.3 536.4 566.5 575.4 7510.2 7525.7 7558.6 7605.0 7707.3 534.0 556.1 557.4 572.8 576.0 3566.4 3585.5 3662.2 3690.5 3771.8 86801.7 87608.6 88695.0 88852.0 89866.1 78317.0 79585.2 79629.2 79677.0 81397.6 69497.0 69589.0 69825.0 70811.0 70878.3 7040.0 7057.1 7170.6 7268.0 7278.4 29649.6 41857.3 42164.0 42503.7 44191.8 54819.9 57613.4 62129.4 64020.0 64084.0 9792.8 9980.1 10024.8 10586.6 10925.3 11709.4 11763.6 12217.0 12235.4 12549.6 10680.4 10789.2 10956.0 11101.6 11201.4 11114.6 11249.1 11350.8 11400.8 11536.7 11195.2 11204.7 11387.5 11517.0 12185.5 9145.5 9168.7 9287.3 9564.5 9578.6 8922.4 8923.6 8997.8 9197.9 9825.1 05:37:37 INFO - PROCESS | 2241 | -------- Summary: end -------- 05:37:37 INFO - PROCESS | 2241 | 05:37:37 INFO - TEST-INFO | 2241: exit 0 05:37:37 INFO - TEST-OK | dromaeo_css | took 1115216ms 05:37:37 INFO - TEST-START | kraken 05:37:37 INFO - operating with platform_type : mac_ 05:37:37 INFO - Initialising browser for kraken test... 05:37:37 INFO - TEST-INFO | started process 2245 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpurEHzl/profile http://localhost:52804/getInfo.html) 05:37:39 INFO - PROCESS | 2245 | __metrics Screen width/height:1600/1200 05:37:39 INFO - PROCESS | 2245 | colorDepth:24 05:37:39 INFO - PROCESS | 2245 | Browser inner width/height: 1024/689 05:37:39 INFO - PROCESS | 2245 | __metrics 05:37:39 INFO - PROCESS | 2245 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:37:39 ERROR - PROCESS | 2245 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:37:40 INFO - TEST-INFO | 2245: exit 0 05:37:40 INFO - Browser initialized. 05:37:40 INFO - Running cycle 1/1 for kraken test... 05:37:40 INFO - TEST-INFO | started process 2248 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpurEHzl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:52 INFO - PROCESS | 2248 | RSS: Main: 238747648 05:37:52 INFO - PROCESS | 2248 | 05:37:55 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52804/tests/kraken/driver.html?testName=audio-beat-detection) 05:37:55 INFO - PROCESS | 2248 | RSS: Main: 286928896 05:37:55 INFO - PROCESS | 2248 | 05:37:58 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52804/tests/kraken/driver.html?testName=audio-dft) 05:37:59 INFO - PROCESS | 2248 | RSS: Main: 299470848 05:37:59 INFO - PROCESS | 2248 | 05:38:03 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52804/tests/kraken/driver.html?testName=audio-fft) 05:38:03 INFO - PROCESS | 2248 | RSS: Main: 385458176 05:38:03 INFO - PROCESS | 2248 | 05:38:05 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52804/tests/kraken/driver.html?testName=audio-oscillator) 05:38:06 INFO - PROCESS | 2248 | RSS: Main: 285495296 05:38:06 INFO - PROCESS | 2248 | 05:38:08 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52804/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:38:09 INFO - PROCESS | 2248 | RSS: Main: 291229696 05:38:09 INFO - PROCESS | 2248 | 05:38:11 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52804/tests/kraken/driver.html?testName=imaging-darkroom) 05:38:12 INFO - PROCESS | 2248 | RSS: Main: 328724480 05:38:12 INFO - PROCESS | 2248 | 05:38:15 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52804/tests/kraken/driver.html?testName=imaging-desaturate) 05:38:16 INFO - PROCESS | 2248 | RSS: Main: 329842688 05:38:16 INFO - PROCESS | 2248 | 05:38:18 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52804/tests/kraken/driver.html?testName=json-parse-financial) 05:38:19 INFO - PROCESS | 2248 | RSS: Main: 327626752 05:38:19 INFO - PROCESS | 2248 | 05:38:21 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52804/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:38:21 INFO - PROCESS | 2248 | RSS: Main: 319946752 05:38:21 INFO - PROCESS | 2248 | 05:38:23 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52804/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:38:24 INFO - PROCESS | 2248 | RSS: Main: 326299648 05:38:24 INFO - PROCESS | 2248 | 05:38:26 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52804/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:38:27 INFO - PROCESS | 2248 | RSS: Main: 329658368 05:38:27 INFO - PROCESS | 2248 | 05:38:30 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52804/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:38:30 INFO - PROCESS | 2248 | RSS: Main: 334336000 05:38:30 INFO - PROCESS | 2248 | 05:38:33 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52804/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:38:34 INFO - PROCESS | 2248 | RSS: Main: 331972608 05:38:34 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | Cycle 1(1): loaded http://localhost:52804/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52804/tests/kraken/driver.html?testName=ai-astar) 05:38:36 INFO - PROCESS | 2248 | RSS: Main: 396259328 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | __start_tp_report 05:38:36 INFO - PROCESS | 2248 | _x_x_mozilla_page_load 05:38:36 INFO - PROCESS | 2248 | _x_x_mozilla_page_load_details 05:38:36 INFO - PROCESS | 2248 | |i|pagename|runs| 05:38:36 INFO - PROCESS | 2248 | |0;ai-astar;113;110;114;110;113;113;119;113;111;112 05:38:36 INFO - PROCESS | 2248 | |1;audio-beat-detection;159;122;146;174;120;144;141;129;203;144 05:38:36 INFO - PROCESS | 2248 | |2;audio-dft;187;188;184;188;186;186;185;262;237;186 05:38:36 INFO - PROCESS | 2248 | |3;audio-fft;78;75;94;93;56;60;57;81;100;83 05:38:36 INFO - PROCESS | 2248 | |4;audio-oscillator;95;92;132;77;80;82;121;78;76;124 05:38:36 INFO - PROCESS | 2248 | |5;imaging-gaussian-blur;90;85;90;86;89;86;90;86;88;87 05:38:36 INFO - PROCESS | 2248 | |6;imaging-darkroom;95;95;95;95;109;148;148;95;95;95 05:38:36 INFO - PROCESS | 2248 | |7;imaging-desaturate;84;85;102;132;140;85;84;85;84;86 05:38:36 INFO - PROCESS | 2248 | |8;json-parse-financial;60;61;59;58;58;58;60;79;60;57 05:38:36 INFO - PROCESS | 2248 | |9;json-stringify-tinderbox;65;65;74;67;72;72;67;70;75;98 05:38:36 INFO - PROCESS | 2248 | |10;stanford-crypto-aes;83;83;83;82;82;104;103;77;83;82 05:38:36 INFO - PROCESS | 2248 | |11;stanford-crypto-ccm;141;141;137;137;143;146;141;137;171;147 05:38:36 INFO - PROCESS | 2248 | |12;stanford-crypto-pbkdf2;173;170;171;173;171;171;167;216;175;169 05:38:36 INFO - PROCESS | 2248 | |13;stanford-crypto-sha256-iterative;71;69;69;69;67;66;64;69;63;60 05:38:36 INFO - PROCESS | 2248 | __end_tp_report 05:38:36 INFO - PROCESS | 2248 | __start_cc_report 05:38:36 INFO - PROCESS | 2248 | _x_x_mozilla_cycle_collect,1978 05:38:36 INFO - PROCESS | 2248 | __end_cc_report 05:38:36 INFO - PROCESS | 2248 | __startTimestamp1460723916263__endTimestamp 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | ------- Summary: start ------- 05:38:36 INFO - PROCESS | 2248 | Number of tests: 14 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:2.57 (2.3%) stddev-sans-first:2.73 05:38:36 INFO - PROCESS | 2248 | Values: 113.0 110.0 114.0 110.0 113.0 113.0 119.0 113.0 111.0 112.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:145.00 stddev:25.23 (17.4%) stddev-sans-first:26.45 05:38:36 INFO - PROCESS | 2248 | Values: 159.0 122.0 146.0 174.0 120.0 144.0 141.0 129.0 203.0 144.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#2] audio-dft Cycles:10 Average:198.90 Median:187.50 stddev:27.34 (14.6%) stddev-sans-first:28.66 05:38:36 INFO - PROCESS | 2248 | Values: 187.0 188.0 184.0 188.0 186.0 186.0 185.0 262.0 237.0 186.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#3] audio-fft Cycles:10 Average:77.70 Median:79.50 stddev:15.83 (19.9%) stddev-sans-first:16.79 05:38:36 INFO - PROCESS | 2248 | Values: 78.0 75.0 94.0 93.0 56.0 60.0 57.0 81.0 100.0 83.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:79.00 stddev:21.75 (27.5%) stddev-sans-first:23.07 05:38:36 INFO - PROCESS | 2248 | Values: 95.0 92.0 132.0 77.0 80.0 82.0 121.0 78.0 76.0 124.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.50 stddev:1.95 (2.2%) stddev-sans-first:1.88 05:38:36 INFO - PROCESS | 2248 | Values: 90.0 85.0 90.0 86.0 89.0 86.0 90.0 86.0 88.0 87.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#6] imaging-darkroom Cycles:10 Average:107.00 Median:95.00 stddev:22.05 (23.2%) stddev-sans-first:22.95 05:38:36 INFO - PROCESS | 2248 | Values: 95.0 95.0 95.0 95.0 109.0 148.0 148.0 95.0 95.0 95.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:84.50 stddev:21.49 (25.4%) stddev-sans-first:22.30 05:38:36 INFO - PROCESS | 2248 | Values: 84.0 85.0 102.0 132.0 140.0 85.0 84.0 85.0 84.0 86.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#8] json-parse-financial Cycles:10 Average:61.00 Median:60.00 stddev:6.45 (10.7%) stddev-sans-first:6.83 05:38:36 INFO - PROCESS | 2248 | Values: 60.0 61.0 59.0 58.0 58.0 58.0 60.0 79.0 60.0 57.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:72.00 stddev:9.65 (13.4%) stddev-sans-first:9.85 05:38:36 INFO - PROCESS | 2248 | Values: 65.0 65.0 74.0 67.0 72.0 72.0 67.0 70.0 75.0 98.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:82.50 stddev:9.30 (11.3%) stddev-sans-first:9.79 05:38:36 INFO - PROCESS | 2248 | Values: 83.0 83.0 83.0 82.0 82.0 104.0 103.0 77.0 83.0 82.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:142.00 stddev:10.09 (7.1%) stddev-sans-first:10.64 05:38:36 INFO - PROCESS | 2248 | Values: 141.0 141.0 137.0 137.0 143.0 146.0 141.0 137.0 171.0 147.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:172.00 stddev:14.37 (8.4%) stddev-sans-first:15.21 05:38:36 INFO - PROCESS | 2248 | Values: 173.0 170.0 171.0 173.0 171.0 171.0 167.0 216.0 175.0 169.0 05:38:36 INFO - PROCESS | 2248 | 05:38:36 INFO - PROCESS | 2248 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:69.00 stddev:3.43 (5.0%) stddev-sans-first:3.27 05:38:36 INFO - PROCESS | 2248 | Values: 71.0 69.0 69.0 69.0 67.0 66.0 64.0 69.0 63.0 60.0 05:38:36 INFO - PROCESS | 2248 | -------- Summary: end -------- 05:38:36 INFO - PROCESS | 2248 | 05:38:37 INFO - TEST-INFO | 2248: exit 0 05:38:37 INFO - TEST-OK | kraken | took 59615ms 05:38:37 INFO - SUITE-END | took 1174s 05:38:37 INFO - Completed test suite (00:19:35) 05:38:37 INFO - javascript benchmark 05:38:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3377.341418423407, 3439.4840773883916, 3477.269773347597, 3481.0427132538557, 3485.5294535237736, 5617.38208797033, 5700.258966334376, 5702.344230413503, 5706.5720070994685, 5723.141528770687, 4752.809887604491, 5667.0, 5781.739821708023, 5797.507211886995, 5828.300603927524, 5139.0, 5169.276301317809, 5184.53339199472, 5202.583793296546, 5290.470952904698, 5299.973500132494, 5307.044731948247, 5322.2282768998475, 5344.4922732340365, 5363.8122665706705, 5142.357205349332, 5241.764120614582, 5244.213367994812, 5248.737563121828, 5253.212018197263, 5143.922841157386, 5156.3296771419855, 5157.406898206679, 5158.896822063575, 5169.896602067937, 5111.6932984020705, 5119.0529751996, 5153.433122356538, 5154.278401023859, 5264.447233040514, 5593.021221286259, 5656.321241451053, 5673.801416950429, 5684.8294551163535, 5686.9763442580315, 6178.196834411543, 6200.3799620037635, 6266.185395898549, 6288.52836037299, 6295.18162638854, 5288.206768984644, 6082.0, 6237.939550276464, 6271.905921411182, 6276.497880169575, 5771.769129234825, 5827.3007239131575, 5850.5904586678525, 5881.9705901470215, 5884.617499862496, 6247.094171345129, 6253.7185826637915, 6278.340774218733, 6282.874342513215, 6297.464715499143, 5308.256844041837, 5932.822015339547, 6083.939160608337, 6186.783462578788, 6188.44304012641, 6149.938500614937, 6181.536384771161, 6193.907091393633, 6205.6586887721605, 6209.875802484015, 6139.369480960552, 6146.063989843275, 6147.047207682816, 6247.312795592481, 6252.124702541648, 6208.1308616793685, 6233.75064997395, 6240.032794916795, 6260.843478913063, 6288.748450061947, 4953.455119936804, 4955.752212389366, 4956.752162391866, 4966.453690094087, 5002.924956125661, 4343.066240758252, 4388.758618275961, 4450.287953927356, 4459.576340247736, 4493.213687604656, 5720.942790572124, 5743.052396354638, 5750.769969201268, 5755.769769209269, 5756.884862302731, 6182.629042257479, 6241.750329986749, 6245.5628106032145, 6258.280297765724, 6282.340354262828, 5596.132599447093, 5695.202671625976, 5700.458456446631, 5713.542916566747, 5721.5136713379, 5365.248865158881, 5398.055340315507, 5399.919001214985, 5402.648827826258, 5404.91892621611, 6247.562670613013, 6253.6873156342, 6255.093011513214, 6256.092866534327, 6261.342559031418, 6207.782727604512, 6213.440790328892, 6243.843903902439, 6248.343923887926, 6273.090401891792, 6278.968605156945, 6282.403171698681, 6291.716872740646, 6298.08677741734, 6307.243130824147, 6055.303640081271, 6142.406692085002, 6179.134921111138, 6188.752449901954, 6193.876122477435, 6179.5056395489155, 6270.341614130451, 6276.152719383008, 6293.087502312231, 6314.147590075281, 5299.549538289289, 5459.181122831607, 6073.210482637229, 6101.84745381369, 6110.969445152746, 5896.970515147397, 6017.428344307284, 6071.150038994456, 6095.664738439428, 6105.17580126695, 5892.234009578728, 5974.9402505974385, 5978.133170690313, 5981.401859813984, 6021.698915054318, 6086.178365920721, 6086.817395478142, 6104.664243466652, 6107.340840728934, 6124.0201567748945, 5828.970855145867, 5952.0179170436395, 5964.522838173022, 6001.189839371629, 6014.81955541317], "value": 5686.585154358934, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.13033061811245, 538.4911258860376, 575.4705670782879, 610.3987572241344, 651.6640886183322, 28970.420591588154, 37138.81430592846, 38329.61670383299, 38386.0, 38511.61488385115, 32978.67021329786, 43152.352714709305, 43242.56757432425, 43297.83510824462, 43500.78249608755, 8445.451045682043, 9412.199963003153, 9642.614295428175, 9672.032796720307, 9673.758156046084, 8539.829203415928, 11504.252223605481, 11818.468168932375, 11827.231229970068, 11841.644750657451, 11452.599159029436, 11587.362695051768, 11590.420478976059, 11592.942035289854, 11687.415629218547, 8520.40357174998, 8757.781055473664, 11446.484908179153, 11577.536898524007, 11615.954564089272, 8566.785830354229, 8675.305975522006, 11371.147163962736, 11731.94134029333, 11732.0, 38036.429453558216, 38498.0, 38589.228215435534, 38644.80677596622, 38685.0, 3089.938201235963, 3233.4664780311455, 3374.780639258465, 3411.7441191910457, 3481.9129521761906, 24622.8919698614, 25236.747632523806, 27184.728152718417, 27270.318242043908, 27426.862865685744, 2674.41162944152, 2678.076063758, 2709.878055487508, 2720.7143249958667, 2794.888204471819, 293057.0, 295615.0, 296016.0, 296584.0, 296793.0, 60018.0, 60550.0, 60635.39364606386, 60638.69680651568, 60720.39279607147, 52681.736591316796, 63604.0, 63796.68101659555, 63800.36199638037, 63852.0, 16106.516804495885, 16564.503064908073, 16567.0, 16574.585635359213, 16662.333506659597, 11593.362365070003, 12679.619611411672, 12926.935365323114, 12985.480580776662, 12986.415611297514, 15867.285972131282, 15901.840981590036, 15938.28277727505, 16034.0, 16246.350145994027, 5655.349634791969, 5802.970985145132, 6840.486963477759, 6847.897281540781, 6915.930840691529, 893.5085702863399, 936.1400920188753, 945.9290553208536, 997.7156510394502, 1044.9289478284818, 28936.276593085342, 29184.854075729487, 29431.705682943324, 30051.088249702254, 30066.84966575153, 1073.1629329123296, 1087.907527860125, 1096.6655170173015, 1146.7075895646556, 1164.2548768787983, 14975.32611032506, 15120.697586048438, 15159.545213643609, 15423.151726654918, 15473.767893481607, 594525.0, 600266.0, 601027.0, 601966.0, 602938.0, 58738.41261587329, 59124.0, 59155.0, 59258.40741592615, 59356.81286374248, 1190.7618476304601, 1327.1042046618493, 1466.222901861972, 1487.263804416799, 1487.5537338798317, 20542.897285513478, 25808.38622619866, 28484.287892802018, 28544.858205671542, 28824.711752883042, 36499.81750091232, 36958.8152059238, 45585.088298234514, 46031.0, 46128.769356153, 345660.0, 349328.5335733254, 349354.0, 349583.0, 349645.0, 32725.34549309048, 37725.0, 38243.617563825115, 39359.60640393674, 40032.0, 41909.371359430246, 42163.78918105512, 42772.358414624425, 42831.57168428276, 43269.35095973626, 34024.17443987847, 35072.64927350694, 35230.0, 35954.0, 36037.09907252339, 35689.75085871982, 35694.464583030756, 35827.641723583474, 36534.63465365312, 36656.633433666386, 35684.821575891954, 35916.4612530807, 36313.63686363103, 36504.81747591245, 36564.81717591395, 30378.69621303847, 31592.842035790592, 31633.841830790698, 31676.732930682516, 31692.683073168973, 17811.643767124846, 23846.0, 24102.638460422757, 24328.75671243265, 24400.87799560991], "value": 19049.58411420033, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.0998014546688, 393.201800345299, 393.9192465544563, 394.12110992486737, 407.079999201804, 19250.61498770024, 19612.90193549032, 20165.59668806623, 20588.97055147244, 20615.38153855383, 18897.0, 19460.902695486537, 20202.090905909234, 20934.685979710317, 20991.790082099174, 5669.574781891354, 5983.341832398432, 6031.155638210654, 6037.909431358533, 6040.969795151018, 5940.732667029973, 5943.970280148615, 6016.6390016598925, 6059.909101363483, 6073.787417440369, 5823.708814559276, 5983.730732117044, 6011.849703757397, 6017.669028203447, 6024.548158888079, 4573.839915602971, 5932.317783454915, 6001.339852616209, 6007.098935159717, 6012.368701286389, 5693.3168019837885, 5722.399148089474, 5728.541716662657, 5730.914036289459, 5731.828045158652, 13406.798898016537, 13473.528426505025, 13489.527866524624, 13530.594082177451, 20896.47758806027, 2100.2964007057653, 2308.3651995701316, 2366.0, 2409.771071748181, 2588.2105957682897, 9704.175145112675, 10206.183505319557, 10348.379097254188, 12471.75056498874, 13283.0, 1688.696034713751, 1737.7132773092424, 1791.2476759760932, 1843.741876137342, 1883.5026154207437, 114909.0, 120679.0, 121012.39493802562, 122768.77231227663, 124595.0, 11617.709557260967, 11643.0, 11678.70803229926, 11681.182317237712, 11846.407679616157, 14916.477923272634, 14978.325975331136, 15028.323725432381, 15043.323050462537, 15043.924780376246, 7111.92888071123, 8663.783405414915, 8695.695650652196, 8709.216170544554, 8819.955900220459, 8525.70160044408, 8528.957355213184, 8555.657773688983, 8629.39594228398, 8666.220040196287, 4572.4055872736335, 4590.173768721662, 4612.976935115303, 4621.907561848744, 4643.096330504477, 1613.322404590075, 1991.6614175589896, 1997.8701384409967, 2001.9699704504444, 2022.6157030164516, 894.6510860764307, 931.7810314576069, 970.1898914406408, 972.4700038479045, 980.127686359141, 16751.58121046959, 19033.238670453306, 19170.329038483585, 19922.90038549798, 20678.966051697356, 934.7289286107089, 956.416585882611, 969.4038166527534, 972.6255391674115, 1033.8035773203067, 10590.417527035934, 10743.946280268548, 10794.0, 10830.0, 10973.615923442641, 509671.4516427394, 517898.0, 518297.0, 602442.0, 603275.0, 132569.33715331362, 137886.0, 138751.999999998, 140865.0, 143435.0, 1020.3622735790132, 1044.7597052677927, 1062.2670357453333, 1064.2869277584036, 1065.1851333729703, 15124.924375378052, 17686.02726849984, 17727.29090836352, 17859.642807143526, 18015.099245037432, 49761.50238497568, 51571.742141289054, 52282.47717522928, 52520.47479525156, 52632.0, 76900.0, 77912.0, 78484.4303113946, 79070.418591629, 81041.18958810573, 7007.509474336747, 7550.584717840571, 7933.294341716559, 8004.5597492138495, 8167.877481837659, 11805.409729513662, 11954.0, 12188.51245950152, 12286.75426491483, 12367.75264494723, 8894.777630559287, 10482.737931551772, 10495.947520262349, 10498.895011050097, 10952.726181845199, 9148.405353651991, 10179.185665146622, 10324.48377581133, 10433.32183408076, 10737.409442480739, 10744.449716554578, 10929.453527323443, 11266.436678166223, 11273.492692829006, 11423.200375973602, 7733.651985660546, 7979.122296547491, 7994.200579942192, 8008.239217274349, 8294.046184688837, 10074.496275186064, 10621.89378106209, 10891.237613366988, 10933.671989840317, 10953.233273671085], "value": 9398.957733091009, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.6888949986248, 1582.5806161367236, 1592.9601759956004, 1802.999999999999, 1937.67059599868, 7790.831375293708, 8457.492550446977, 8701.521416322099, 8709.956450217747, 8740.956295218515, 8336.874946875801, 8553.016403113645, 8559.657613695445, 8566.828663426728, 8569.185927336908, 2140.7324084489437, 2350.4006478347997, 2399.9999999999955, 2440.4143005678598, 2447.204658485999, 2448.3634255093716, 2468.395243165427, 2482.950340993179, 2487.2786891801447, 2491.1032028469735, 2075.785665385751, 2414.8309618326725, 2444.1201167579657, 2449.2040086971715, 2455.4966231922413, 2115.185653523397, 2326.8371214015024, 2407.723111842143, 2413.722421921484, 2415.384077060347, 2415.577273977047, 2437.756224377566, 2439.890204940782, 2440.7803297703113, 2443.438009257881, 8621.0, 8660.610272537751, 8668.39321247513, 8669.826603467895, 8685.435446695945, 4142.5857414258635, 5204.245384419276, 5283.181106928394, 5294.470552944713, 5302.310699609065, 2871.3539453622975, 3475.826208689555, 3514.8242587870504, 3604.1530240393486, 3604.441311596707, 1008.0071129937079, 1015.3755440404173, 1017.2675673515057, 1029.1663752360612, 1047.1570385839395, 11134.0, 11393.601223957205, 11433.0, 11488.88511114895, 11537.711557211052, 4472.664550158751, 4557.703749256288, 4646.83736069236, 4691.39011928454, 4692.648051396159, 8966.803133232399, 9037.367384283038, 9055.95472022649, 9059.592318345558, 9066.773330666787, 5229.869253268699, 5235.738213089407, 5240.842774716764, 5248.05535003703, 5279.736013199325, 4359.891002724958, 4518.390017347616, 4704.223803072455, 4860.343853579792, 4886.193778026657, 3728.515293011891, 3763.3226019316785, 3840.116773142137, 3852.845886164578, 3856.382978723391, 1039.8284283093205, 1192.362086283834, 1198.4547031100828, 1209.9395030248452, 1211.2611307102661, 823.2714048067503, 841.7643059943226, 852.1308265569085, 906.9138431848963, 943.1888575824922, 5808.419158084243, 5826.912596311059, 5898.439648233497, 6052.788152414732, 6105.931461994222, 907.1291560102376, 963.6001059560203, 965.6571916969515, 985.640241311916, 1010.1565193063874, 3634.8727794527063, 3917.745346552465, 3986.402039694069, 3991.2416640838146, 3993.7603743775467, 9552.522373881417, 9604.94345621981, 9941.751456213653, 10620.468976551141, 10761.354318740743, 8956.462612243286, 10234.846477302846, 10530.21023423231, 10687.572497100184, 10695.304805187638, 7788.4548081634875, 8091.19088091198, 8118.0, 8123.75628731139, 8143.552104634184, 5557.888842223214, 5716.5998380113, 5730.541556675539, 5814.273215848019, 5835.824925252249, 9763.218942484726, 9793.706188814347, 9808.950955245178, 9879.160271377013, 9952.10431061179, 9570.712878613653, 9754.853677194706, 9886.4562449066, 9994.750131246778, 10142.340747851365, 6382.776602818879, 6637.303083176197, 6654.0, 6806.625635589892, 6949.30506949301, 8218.260356567938, 8223.95888020556, 8302.169783021649, 8309.501429914224, 8319.584020798815, 7181.533200341961, 7368.557886526611, 7388.519746216479, 7410.703571857065, 7557.962210188914, 7167.1757747859665, 7212.945600301589, 7239.674214660247, 7306.890396643948, 7363.15323737777, 6901.206361268318, 6916.515843890879, 6971.51199416036, 6976.19773726028, 6991.0, 4797.712137271775, 4845.903081938271, 4888.6577939543895, 4944.480829512993, 5073.720945347894, 4868.736037742467, 4894.510548945077, 4941.950580494149, 4993.27597498368, 5158.535731784157], "value": 4504.680507522783, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.75065072616536, 320.23783395518666, 328.33512137920724, 328.61880218946027, 329.8779451602907, 4683.672142949995, 4924.606031517479, 5892.909811684841, 5945.227120474337, 5986.790462333819, 4984.975075124619, 5350.250964864922, 5675.3757086720425, 5831.30024397073, 5881.265211676569, 4260.0, 4495.775211239433, 4501.707389019703, 4510.233260345749, 4521.186186486431, 3496.0283758805067, 4365.607095361417, 4490.550944905516, 4537.70504917181, 4547.272036626077, 3934.980325098385, 4211.7262377945335, 4323.783810809463, 4356.063446359032, 4395.274779661368, 4400.361947517608, 4405.0969551241915, 4408.801603927831, 4440.467143942716, 4448.932256258507, 3559.875404360835, 3779.1496913194587, 3779.508663873679, 3785.470034195215, 3808.257389808988, 5273.894522109575, 5645.830625081213, 5715.685637289947, 5719.7140142992685, 5720.771169153229, 4303.902504861272, 4765.380500534909, 4884.511548845087, 5068.518490743373, 5069.986002799418, 5326.653767505139, 5851.707414629252, 5854.882902341972, 6167.629942203437, 6232.875342493125, 4062.6749860011128, 4070.857519986816, 4292.205941900758, 4322.351647252905, 4332.7400355978425, 5577.52591029763, 6206.565540412173, 6401.423871851509, 6406.295307516216, 6521.250056243545, 4192.853250136231, 4400.44994375703, 4418.8453404130705, 4419.607823535576, 4422.270325396274, 4172.436721042621, 4267.509236437787, 4476.2837945928495, 4493.236149854532, 4496.437945256843, 4214.946263434142, 4548.408706868086, 5148.33071700684, 5191.273221748958, 5194.818181363634, 6244.815144276361, 6248.4069111318295, 6252.531060170505, 6304.275008374004, 6304.401081897213, 1309.6005718256024, 1504.428317239464, 1536.3393740691386, 1536.377767004365, 1545.389571119424, 965.6909788867495, 992.8709267795141, 1012.6810054832704, 1023.2888142740724, 1032.1742605915238, 5784.82645520635, 5815.87720482021, 5836.9124463133085, 5843.298804143445, 5850.180974663551, 5622.91565626516, 5628.802991895264, 5776.971115144482, 6348.904766428507, 6446.129772480655, 5114.71869047206, 5160.8967820643375, 5602.803901863415, 5825.592208545361, 5829.504492118217, 3369.5451114099774, 3394.2193295542165, 3408.2672225471642, 3409.3522230776075, 3412.5734283214597, 5633.57748168889, 6430.967845160744, 6715.2613212546585, 6744.966275168691, 6878.55289406187, 5645.435456454322, 6682.532222744361, 6705.631190284484, 6731.730730770812, 6745.662716864137, 6319.431251187415, 6799.558028728117, 6830.282820303896, 6845.418139458102, 6859.124594604033, 5931.822045338647, 6296.496280297657, 6417.294097649162, 6438.259600146043, 6498.675066246763, 5400.702961337164, 6318.515148940088, 6374.012028135647, 6378.77674281398, 6414.524659328287, 6345.809625711236, 6788.0, 6805.332693490127, 6810.761623343159, 6814.659267036727, 4122.587741225853, 4190.350495673176, 4203.327467605169, 4210.7473551587, 4219.53585105632, 5358.651687640291, 5743.4543718346695, 6114.7554097837365, 6466.773662921775, 6488.6755662215755, 6169.167162433194, 6230.719617617127, 6250.249970003629, 6259.780907668392, 6286.308506064421, 5275.577953763766, 6218.9378106218355, 6244.0, 6250.218722659668, 6250.593711408744, 5812.94187058141, 5839.416058394127, 6121.663308517896, 6124.938750612437, 6130.785422510191, 5015.398152221756, 5477.260569823023, 6134.8159555213415, 6231.937680623317, 6266.342034086355, 5929.347771745191, 6113.786017489367, 6128.571000029955, 6142.846428839315, 6149.292831324455], "value": 4546.36934536954, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.619609214932, 698.8078278473422, 741.6254791330377, 765.6171914042978, 769.8729709597915, 15405.537833864973, 15565.92217038916, 15759.290831912564, 15760.842391576081, 15762.133082680448, 13926.234057126854, 14023.509177178801, 14068.437262509513, 14079.648008799759, 14088.577342679737, 618.5824568416324, 756.6179079564812, 904.0913881549034, 920.1626519866923, 924.191332583989, 871.7602659268689, 907.2787134228304, 907.9682211122612, 909.4088842252555, 910.927125829932, 950.8240975419535, 969.9854502182473, 975.551246426641, 976.3458482816494, 978.7895602445473, 759.5025258455693, 916.2303664921432, 947.0576776107816, 961.7403301108696, 964.0118878149882, 705.3158436316764, 705.4427002667886, 706.3466293678327, 707.1302298173275, 707.2291202589203, 15101.622459438491, 15232.314545845464, 15238.390464381411, 15240.923795381063, 15245.61885952849, 10754.462276886125, 12707.428165732565, 12999.545015924397, 13010.674733131653, 13279.402426890812, 10090.445025523593, 10102.797944041151, 10201.897981020169, 10554.947225263824, 10651.094656954168, 2980.970190298091, 3511.1924257420683, 3517.3317069756663, 3587.228745819635, 3632.3098611263777, 79857.60071199665, 80195.0, 82028.0, 82172.17827821705, 83408.58295708483, 9535.332526723063, 9551.761205969906, 9731.221502279801, 9786.266030047633, 10122.949385253027, 22108.447288817588, 22113.447163821034, 22121.004554795072, 22135.114595416326, 22146.677998300383, 7013.123359580052, 7224.927750722425, 7434.36807871337, 7480.855782727483, 7558.659860306299, 3439.6904278615043, 4091.7135800494275, 4114.588541145861, 4176.2900306947895, 4213.4522512073845, 3001.834899080528, 3455.550778398792, 3562.8752993645617, 3570.4822800693755, 3585.8207089645935, 526.8050821196182, 603.0953569645532, 604.332212904734, 616.5468380740152, 628.7579281976472, 460.3233247126718, 493.3339991012105, 536.3724442402398, 566.4788394676907, 575.3900865083025, 7510.248975102555, 7525.698972041167, 7558.58427786466, 7604.961975190199, 7707.344875685518, 533.9759710813023, 556.115775916295, 557.3868744381148, 572.7880684146809, 576.0466428061482, 3566.4115420955773, 3585.4801053847045, 3662.212624285791, 3690.450784890803, 3771.849126034983, 86801.69797453043, 87608.56195718981, 88695.0, 88852.0, 89866.10133898708, 78317.0, 79585.20414795894, 79629.20370796334, 79677.0, 81397.59301203575, 69497.0, 69589.0, 69825.0, 70811.0, 70878.2912170882, 7039.964800175966, 7057.082579264663, 7170.569765814068, 7267.963660181666, 7278.417726581755, 29649.5552566716, 41857.34925785102, 42164.0, 42503.724888253404, 44191.776755231534, 54819.90360192694, 57613.42386576248, 62129.37870621236, 64020.0, 64084.0, 9792.80414391694, 9980.101790838859, 10024.799504010025, 10586.629467968583, 10925.344479331265, 11709.355985420883, 11763.588274410697, 12217.0226381891, 12235.449404776627, 12549.623511294676, 10680.359178449318, 10789.190810689073, 10956.013958743433, 11101.611443599435, 11201.43992800373, 11114.555417783198, 11249.100071994384, 11350.829737554108, 11400.771984560097, 11536.653900383002, 11195.216334856483, 11204.71988200269, 11387.487563059516, 11517.0, 12185.451654675739, 9145.451272923647, 9168.724938251864, 9287.349885507949, 9564.4739539326, 9578.56896439648, 8922.375433719577, 8923.643054277756, 8997.82004359896, 9197.862032069657, 9825.133224687572], "value": 6709.446748753121, "unit": "score"}], "name": "dromaeo_css", "value": 7205.1897633609815, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 110.0, 114.0, 110.0, 113.0, 113.0, 119.0, 113.0, 111.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 122.0, 146.0, 174.0, 120.0, 144.0, 141.0, 129.0, 203.0, 144.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 184.0, 188.0, 186.0, 186.0, 185.0, 262.0, 237.0, 186.0], "value": 198.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 94.0, 93.0, 56.0, 60.0, 57.0, 81.0, 100.0, 83.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 92.0, 132.0, 77.0, 80.0, 82.0, 121.0, 78.0, 76.0, 124.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 90.0, 86.0, 89.0, 86.0, 90.0, 86.0, 88.0, 87.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 109.0, 148.0, 148.0, 95.0, 95.0, 95.0], "value": 107.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 102.0, 132.0, 140.0, 85.0, 84.0, 85.0, 84.0, 86.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 58.0, 58.0, 58.0, 60.0, 79.0, 60.0, 57.0], "value": 61.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 74.0, 67.0, 72.0, 72.0, 67.0, 70.0, 75.0, 98.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 82.0, 82.0, 104.0, 103.0, 77.0, 83.0, 82.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 141.0, 137.0, 137.0, 143.0, 146.0, 141.0, 137.0, 171.0, 147.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 171.0, 173.0, 171.0, 171.0, 167.0, 216.0, 175.0, 169.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 69.0, 67.0, 66.0, 64.0, 69.0, 63.0, 60.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1530.8000000000002, "alertThreshold": 2.0}]} 05:38:37 INFO - Return code: 0 05:38:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:38:37 ERROR - # TBPL SUCCESS # 05:38:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:38:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:38:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:38:37 INFO - 2016-04-15 05:38:37.601 screenresolution[2252:20029] starting screenresolution argv=screenresolution get 05:38:37 INFO - 2016-04-15 05:38:37.604 screenresolution[2252:20029] Display 0: 1600x1200x32@60 05:38:37 INFO - 2016-04-15 05:38:37.610 screenresolution[2253:20032] starting screenresolution argv=screenresolution list 05:38:37 INFO - Available Modes on Display 0 05:38:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:38:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:38:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:38:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:38:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:38:37 INFO - Intel Iris: 05:38:37 INFO - Chipset Model: Intel Iris 05:38:37 INFO - Type: GPU 05:38:37 INFO - Bus: Built-In 05:38:37 INFO - VRAM (Dynamic, Max): 1536 MB 05:38:37 INFO - Vendor: Intel (0x8086) 05:38:37 INFO - Device ID: 0x0a2e 05:38:37 INFO - Revision ID: 0x0009 05:38:37 INFO - Displays: 05:38:37 INFO - Display: 05:38:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:38:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:38:37 INFO - Display Serial Number: 1600x1200 60 05:38:37 INFO - Main Display: Yes 05:38:37 INFO - Mirror: Off 05:38:37 INFO - Online: Yes 05:38:37 INFO - Rotation: Supported 05:38:37 INFO - Return code: 0 05:38:37 INFO - Running post-action listener: _resource_record_post_action 05:38:37 INFO - Running post-run listener: _resource_record_post_run 05:38:38 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 249856; Write bytes: 689872384; Read time: 3; Write time: 1839 05:38:38 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394899968; Write bytes: 396666368; Read time: 29686; Write time: 708 05:38:38 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 249856; Write bytes: 292284416; Read time: 3; Write time: 1128 05:38:38 INFO - Running post-run listener: _upload_blobber_files 05:38:38 INFO - Blob upload gear active. 05:38:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:38:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:38:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:38:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:38:39 INFO - (blobuploader) - INFO - Open directory for files ... 05:38:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:38:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:38:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:40 INFO - (blobuploader) - INFO - Done attempting. 05:38:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:38:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:38:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:38:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:38:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:38:40 INFO - (blobuploader) - INFO - Done attempting. 05:38:40 INFO - (blobuploader) - INFO - Iteration through files over. 05:38:40 INFO - Return code: 0 05:38:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:38:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:38:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09db84134423b5f0be8d197cefe4f5e3d6f2607eb5fa7dc8d12cd1f09a20ab749338e0c82fa4c1ae401e2c965dcd0ee4f3f1349b4c5bba0e0ab51cbbc49ef8f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bc3a34ea5b8c27b9bdb12f4587292efbcfb5492096e168a654cb53343646d80e517c9c89345637c8ba3f7c36f9e5df7334cb356ca82ed99e59cf2f5df0ce3f4"} 05:38:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:38:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:38:40 INFO - Contents: 05:38:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09db84134423b5f0be8d197cefe4f5e3d6f2607eb5fa7dc8d12cd1f09a20ab749338e0c82fa4c1ae401e2c965dcd0ee4f3f1349b4c5bba0e0ab51cbbc49ef8f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bc3a34ea5b8c27b9bdb12f4587292efbcfb5492096e168a654cb53343646d80e517c9c89345637c8ba3f7c36f9e5df7334cb356ca82ed99e59cf2f5df0ce3f4"} 05:38:40 INFO - Running post-run listener: copy_logs_to_upload_dir 05:38:40 INFO - Copying logs to upload dir... 05:38:40 INFO - mkdir: /builds/slave/test/build/upload/logs 05:38:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.797627 ========= master_lag: 38.35 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 45 secs) (at 2016-04-15 05:39:19.341869) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2016-04-15 05:39:19.344692) ========= 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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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/09db84134423b5f0be8d197cefe4f5e3d6f2607eb5fa7dc8d12cd1f09a20ab749338e0c82fa4c1ae401e2c965dcd0ee4f3f1349b4c5bba0e0ab51cbbc49ef8f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bc3a34ea5b8c27b9bdb12f4587292efbcfb5492096e168a654cb53343646d80e517c9c89345637c8ba3f7c36f9e5df7334cb356ca82ed99e59cf2f5df0ce3f4"} build_url:https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012134 build_url: 'https://queue.taskcluster.net/v1/task/LYdSSttMTriDPSTnQk5l8Q/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/09db84134423b5f0be8d197cefe4f5e3d6f2607eb5fa7dc8d12cd1f09a20ab749338e0c82fa4c1ae401e2c965dcd0ee4f3f1349b4c5bba0e0ab51cbbc49ef8f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6bc3a34ea5b8c27b9bdb12f4587292efbcfb5492096e168a654cb53343646d80e517c9c89345637c8ba3f7c36f9e5df7334cb356ca82ed99e59cf2f5df0ce3f4"}' ========= master_lag: 9.37 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 9 secs) (at 2016-04-15 05:39:28.729417) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-15 05:39:28.729716) ========= 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.FuowKzF5zz/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.YS1gnOZqWJ/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.005419 ========= master_lag: 3.58 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-15 05:39:32.312235) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-04-15 05:39:32.312549) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-04-15 05:39:35.223729) ========= ========= Total master_lag: 51.37 =========