builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0175 starttime: 1461329250.62 results: success (0) buildid: 20160419230832 builduid: 1ab072c2db0d4b259b7f46e10e93ac05 revision: d42b25947fc26a0f9eab98d631465b72769553bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:30.615401) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:30.615799) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:30.616075) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:30.637482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:30.637867) ========= 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.g47mmQK83Y/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.hXQGHeGMUX/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-22 05:47:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.79M=0.001s 2016-04-22 05:47:30 (8.79 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098427 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:30.753844) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:30.754172) ========= 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.g47mmQK83Y/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.hXQGHeGMUX/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.027343 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:30.806088) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-22 05:47:30.809489) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d42b25947fc26a0f9eab98d631465b72769553bd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d42b25947fc26a0f9eab98d631465b72769553bd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g47mmQK83Y/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.hXQGHeGMUX/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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-22 05:47:30,887 truncating revision to first 12 chars 2016-04-22 05:47:30,887 Setting DEBUG logging. 2016-04-22 05:47:30,887 attempt 1/10 2016-04-22 05:47:30,887 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d42b25947fc2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 05:47:32,007 unpacking tar archive at: fx-team-d42b25947fc2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.368401 ========= master_lag: 0.85 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-22 05:47:33.023645) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:33.023922) ========= script_repo_revision: d42b25947fc26a0f9eab98d631465b72769553bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:33.024301) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:33.024582) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 05:47:33.046610) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-22 05:47:33.046999) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g47mmQK83Y/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.hXQGHeGMUX/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:47:33 INFO - MultiFileLogger online at 20160422 05:47:33 in /builds/slave/test 05:47:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:47:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:47:33 INFO - {'append_to_log': False, 05:47:33 INFO - 'base_work_dir': '/builds/slave/test', 05:47:33 INFO - 'blob_upload_branch': 'Fx-Team', 05:47:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:47:33 INFO - 'branch': 'Fx-Team', 05:47:33 INFO - 'buildbot_json_path': 'buildprops.json', 05:47:33 INFO - 'config_files': ('talos/mac_config.py',), 05:47:33 INFO - 'default_actions': ('clobber', 05:47:33 INFO - 'read-buildbot-config', 05:47:33 INFO - 'download-and-extract', 05:47:33 INFO - 'populate-webroot', 05:47:33 INFO - 'create-virtualenv', 05:47:33 INFO - 'install', 05:47:33 INFO - 'run-tests'), 05:47:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:47:33 INFO - 'download_minidump_stackwalk': True, 05:47:33 INFO - 'download_symbols': 'ondemand', 05:47:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:47:33 INFO - 'tooltool.py': '/tools/tooltool.py', 05:47:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:47:33 INFO - '/tools/misc-python/virtualenv.py')}, 05:47:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:47:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:47:33 INFO - 'installer_path': 'installer.exe', 05:47:33 INFO - 'log_level': 'info', 05:47:33 INFO - 'log_name': 'talos', 05:47:33 INFO - 'log_to_console': True, 05:47:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:47:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:47:33 INFO - 'opt_config_files': (), 05:47:33 INFO - 'pip_index': False, 05:47:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:33 INFO - 'cmd': ('bash', 05:47:33 INFO - '-c', 05:47:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:33 INFO - 'enabled': True, 05:47:33 INFO - 'halt_on_failure': False, 05:47:33 INFO - 'name': 'check_screen_resolution'},), 05:47:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:47:33 INFO - 'cmd': ('bash', 05:47:33 INFO - '-c', 05:47:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:47:33 INFO - 'enabled': True, 05:47:33 INFO - 'halt_on_failure': False, 05:47:33 INFO - 'name': 'check_screen_resolution'},), 05:47:33 INFO - 'run_cmd_checks_enabled': True, 05:47:33 INFO - 'sps_profile': False, 05:47:33 INFO - 'sps_profile_interval': 0, 05:47:33 INFO - 'suite': 'dromaeojs', 05:47:33 INFO - 'system_bits': '32', 05:47:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:47:33 INFO - 'title': 't-yosemite-r7-0175', 05:47:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:47:33 INFO - 'use_talos_json': True, 05:47:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:47:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:47:33 INFO - 'work_dir': 'build'} 05:47:33 INFO - ##### 05:47:33 INFO - ##### Running clobber step. 05:47:33 INFO - ##### 05:47:33 INFO - Running pre-action listener: _resource_record_pre_action 05:47:33 INFO - Running main action method: clobber 05:47:33 INFO - rmtree: /builds/slave/test/build 05:47:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:47:34 INFO - Running post-action listener: _resource_record_post_action 05:47:34 INFO - ##### 05:47:34 INFO - ##### Running read-buildbot-config step. 05:47:34 INFO - ##### 05:47:34 INFO - Running pre-action listener: _resource_record_pre_action 05:47:34 INFO - Running main action method: read_buildbot_config 05:47:34 INFO - Using buildbot properties: 05:47:34 INFO - { 05:47:34 INFO - "project": "", 05:47:34 INFO - "product": "firefox", 05:47:34 INFO - "script_repo_revision": "production", 05:47:34 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:47:34 INFO - "repository": "", 05:47:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:47:34 INFO - "buildid": "20160419230832", 05:47:34 INFO - "pgo_build": "False", 05:47:34 INFO - "basedir": "/builds/slave/test", 05:47:34 INFO - "buildnumber": 700, 05:47:34 INFO - "slavename": "t-yosemite-r7-0175", 05:47:34 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:47:34 INFO - "platform": "yosemite_r7", 05:47:34 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:47:34 INFO - "branch": "fx-team", 05:47:34 INFO - "repo_path": "integration/fx-team", 05:47:34 INFO - "revision": "d42b25947fc26a0f9eab98d631465b72769553bd", 05:47:34 INFO - "stage_platform": "macosx64", 05:47:34 INFO - "builduid": "1ab072c2db0d4b259b7f46e10e93ac05", 05:47:34 INFO - "slavebuilddir": "test" 05:47:34 INFO - } 05:47:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:47:34 INFO - Running post-action listener: _resource_record_post_action 05:47:34 INFO - ##### 05:47:34 INFO - ##### Running download-and-extract step. 05:47:34 INFO - ##### 05:47:34 INFO - Running pre-action listener: _resource_record_pre_action 05:47:34 INFO - Running main action method: download_and_extract 05:47:34 INFO - mkdir: /builds/slave/test/build/tests 05:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:34 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/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:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/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:47:37 INFO - Downloaded 1199 bytes. 05:47:37 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:47:37 INFO - Using the following test package requirements: 05:47:37 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:47:37 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:37 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:47:37 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:37 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:47:37 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:37 INFO - u'jsshell-mac.zip'], 05:47:37 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:37 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:47:37 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:47:37 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:37 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:47:37 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:37 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:47:37 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:37 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:47:37 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:47:37 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:47:37 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:47: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:47:37 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:47:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/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:47:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/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:47:39 INFO - Downloaded 18095480 bytes. 05:47:39 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:47:39 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:47:42 INFO - Return code: 0 05:47: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:47: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:47:42 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:47:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:47:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/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:47:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/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:47:44 INFO - Downloaded 18095480 bytes. 05:47:44 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:47:44 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:47:48 INFO - Return code: 0 05:47: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:47:48 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:47:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:47:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:47:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/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:47:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/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:47:49 INFO - Downloaded 10845952 bytes. 05:47:49 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:47:49 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:47:49 INFO - Return code: 0 05:47:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:49 INFO - https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:47:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:47:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:47:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:47:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I4vTWX0DROiS0WyhGRP33Q/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:47:52 INFO - Downloaded 110044454 bytes. 05:47:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:47:52 INFO - mkdir: /builds/slave/test/properties 05:47:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:47:52 INFO - Writing to file /builds/slave/test/properties/build_url 05:47:52 INFO - Contents: 05:47:52 INFO - build_url:https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:47:53 INFO - Running post-action listener: _resource_record_post_action 05:47:53 INFO - Running post-action listener: set_extra_try_arguments 05:47:53 INFO - ##### 05:47:53 INFO - ##### Running populate-webroot step. 05:47:53 INFO - ##### 05:47:53 INFO - Running pre-action listener: _resource_record_pre_action 05:47:53 INFO - Running main action method: populate_webroot 05:47:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:47:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:47:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:47:53 INFO - '--tppagecycles', 05:47:53 INFO - '7'], 05:47:53 INFO - 'tests': ['tsvgm']}}, 05:47:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:47:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:47:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:47:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:47:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:53 INFO - 'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:53 INFO - 'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['damp', 'tps']}, 05:47:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:53 INFO - 'tests': ['damp', 'tps']}, 05:47:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['dromaeo_dom']}, 05:47:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:47:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['a11yr', 05:47:53 INFO - 'ts_paint', 05:47:53 INFO - 'tpaint', 05:47:53 INFO - 'sessionrestore', 05:47:53 INFO - 'sessionrestore_no_auto_restore', 05:47:53 INFO - 'tabpaint']}, 05:47:53 INFO - 'other-e10s': {'tests': ['a11yr', 05:47:53 INFO - 'ts_paint', 05:47:53 INFO - 'tpaint', 05:47:53 INFO - 'sessionrestore', 05:47:53 INFO - 'sessionrestore_no_auto_restore', 05:47:53 INFO - 'tabpaint']}, 05:47:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:47:53 INFO - 'ts_paint', 05:47:53 INFO - 'tpaint', 05:47:53 INFO - 'sessionrestore', 05:47:53 INFO - 'sessionrestore_no_auto_restore', 05:47:53 INFO - 'tabpaint']}, 05:47:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:47:53 INFO - 'ts_paint', 05:47:53 INFO - 'tpaint', 05:47:53 INFO - 'sessionrestore', 05:47:53 INFO - 'sessionrestore_no_auto_restore', 05:47:53 INFO - 'tabpaint']}, 05:47:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['a11yr', 05:47:53 INFO - 'ts_paint', 05:47:53 INFO - 'tpaint', 05:47:53 INFO - 'sessionrestore', 05:47:53 INFO - 'sessionrestore_no_auto_restore', 05:47:53 INFO - 'tabpaint']}, 05:47:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['a11yr', 05:47:53 INFO - 'ts_paint', 05:47:53 INFO - 'tpaint', 05:47:53 INFO - 'sessionrestore', 05:47:53 INFO - 'sessionrestore_no_auto_restore', 05:47:53 INFO - 'tabpaint']}, 05:47:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['tsvgx', 05:47:53 INFO - 'tsvgr_opacity', 05:47:53 INFO - 'tart', 05:47:53 INFO - 'tscrollx', 05:47:53 INFO - 'cart']}, 05:47:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:47:53 INFO - 'tsvgr_opacity', 05:47:53 INFO - 'tart', 05:47:53 INFO - 'tscrollx', 05:47:53 INFO - 'cart']}, 05:47:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:53 INFO - 'talos_options': ['--disable-e10s'], 05:47:53 INFO - 'tests': ['tp5o']}, 05:47:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:53 INFO - 'tests': ['tp5o']}, 05:47:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:53 INFO - 'talos_options': ['--disable-e10s', 05:47:53 INFO - '--xperf_path', 05:47:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:53 INFO - 'tests': ['tp5n']}, 05:47:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:47:53 INFO - 'talos_options': ['--xperf_path', 05:47:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:53 INFO - 'tests': ['tp5n']}}, 05:47:53 INFO - 'talos.zip': {'path': '', 05:47:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:47:53 INFO - Running post-action listener: _resource_record_post_action 05:47:53 INFO - ##### 05:47:53 INFO - ##### Running create-virtualenv step. 05:47:53 INFO - ##### 05:47:53 INFO - Running pre-action listener: _resource_record_pre_action 05:47:53 INFO - Running main action method: create_virtualenv 05:47:53 INFO - Creating virtualenv /builds/slave/test/build/venv 05:47:53 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:47:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:47:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:47:53 INFO - Using real prefix '/tools/python27' 05:47:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:47:54 INFO - Installing distribute.............................................................................................................................................................................................done. 05:47:57 INFO - Installing pip.................done. 05:47:57 INFO - Return code: 0 05:47:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:57 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 0x103ea7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57a5beb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, '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.g47mmQK83Y/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.hXQGHeGMUX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:47:57 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:47:57 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:47:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:47:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:57 INFO - 'HOME': '/Users/cltbld', 05:47:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:57 INFO - 'LOGNAME': 'cltbld', 05:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:57 INFO - 'MOZ_NO_REMOTE': '1', 05:47:57 INFO - 'NO_EM_RESTART': '1', 05:47:57 INFO - 'PAGER': '/bin/cat', 05:47:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:57 INFO - 'PWD': '/builds/slave/test', 05:47:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:57 INFO - 'SHELL': '/bin/bash', 05:47:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:47:57 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:47:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:57 INFO - 'USER': 'cltbld', 05:47:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:57 INFO - 'XPC_FLAGS': '0x0', 05:47:57 INFO - 'XPC_SERVICE_NAME': '0', 05:47:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:57 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:47:57 INFO - Cleaning up... 05:47:57 INFO - Return code: 0 05:47:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:57 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 0x103ea7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57a5beb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, '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.g47mmQK83Y/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.hXQGHeGMUX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:47:57 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:47:57 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:47:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:47:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:57 INFO - 'HOME': '/Users/cltbld', 05:47:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:57 INFO - 'LOGNAME': 'cltbld', 05:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:57 INFO - 'MOZ_NO_REMOTE': '1', 05:47:57 INFO - 'NO_EM_RESTART': '1', 05:47:57 INFO - 'PAGER': '/bin/cat', 05:47:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:57 INFO - 'PWD': '/builds/slave/test', 05:47:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:57 INFO - 'SHELL': '/bin/bash', 05:47:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:47:57 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:47:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:57 INFO - 'USER': 'cltbld', 05:47:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:57 INFO - 'XPC_FLAGS': '0x0', 05:47:57 INFO - 'XPC_SERVICE_NAME': '0', 05:47:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:57 INFO - Downloading/unpacking psutil>=0.7.1 05:47:57 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:47:57 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:47:57 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:47:57 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:47:57 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:47:57 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:48:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:48:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:00 INFO - Installing collected packages: psutil 05:48:00 INFO - Running setup.py install for psutil 05:48:00 INFO - building 'psutil._psutil_osx' extension 05:48: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:48:01 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:48:01 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:48:01 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:48:01 INFO - building 'psutil._psutil_posix' extension 05:48:01 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:48:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:48:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:48:01 INFO - ^ 05:48:01 INFO - 1 warning generated. 05:48:01 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:48:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:48:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:48:01 INFO - Successfully installed psutil 05:48:01 INFO - Cleaning up... 05:48:02 INFO - Return code: 0 05:48:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:48:02 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:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:02 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:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:02 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 0x103ea7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57a5beb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, '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.g47mmQK83Y/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.hXQGHeGMUX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:48:02 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:48:02 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:48:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:48:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:02 INFO - 'HOME': '/Users/cltbld', 05:48:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:02 INFO - 'LOGNAME': 'cltbld', 05:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:02 INFO - 'MOZ_NO_REMOTE': '1', 05:48:02 INFO - 'NO_EM_RESTART': '1', 05:48:02 INFO - 'PAGER': '/bin/cat', 05:48:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:02 INFO - 'PWD': '/builds/slave/test', 05:48:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:02 INFO - 'SHELL': '/bin/bash', 05:48:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:48:02 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:48:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:02 INFO - 'USER': 'cltbld', 05:48:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:02 INFO - 'XPC_FLAGS': '0x0', 05:48:02 INFO - 'XPC_SERVICE_NAME': '0', 05:48:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:48:04 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:48:04 INFO - Installing collected packages: mozsystemmonitor 05:48:04 INFO - Running setup.py install for mozsystemmonitor 05:48:04 INFO - Successfully installed mozsystemmonitor 05:48:04 INFO - Cleaning up... 05:48:04 INFO - Return code: 0 05:48:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:48:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ea7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57a5beb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, '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.g47mmQK83Y/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.hXQGHeGMUX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:48:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:48:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:48:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:48:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:04 INFO - 'HOME': '/Users/cltbld', 05:48:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:04 INFO - 'LOGNAME': 'cltbld', 05:48:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:04 INFO - 'MOZ_NO_REMOTE': '1', 05:48:04 INFO - 'NO_EM_RESTART': '1', 05:48:04 INFO - 'PAGER': '/bin/cat', 05:48:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:04 INFO - 'PWD': '/builds/slave/test', 05:48:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:04 INFO - 'SHELL': '/bin/bash', 05:48:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:48:04 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:48:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:04 INFO - 'USER': 'cltbld', 05:48:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:04 INFO - 'XPC_FLAGS': '0x0', 05:48:04 INFO - 'XPC_SERVICE_NAME': '0', 05:48:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:05 INFO - Downloading/unpacking blobuploader==1.2.4 05:48:05 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:48:05 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:48:05 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:48:05 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:48:05 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:48:05 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:48:07 INFO - Downloading blobuploader-1.2.4.tar.gz 05:48:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:48:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:48:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:48:08 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:48:08 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:48:08 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:48:08 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:48:08 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:48:08 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:48:08 INFO - Downloading docopt-0.6.1.tar.gz 05:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:48:08 INFO - Installing collected packages: blobuploader, requests, docopt 05:48:08 INFO - Running setup.py install for blobuploader 05:48:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:48:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:48:09 INFO - Running setup.py install for requests 05:48:09 INFO - Running setup.py install for docopt 05:48:09 INFO - Successfully installed blobuploader requests docopt 05:48:09 INFO - Cleaning up... 05:48:09 INFO - Return code: 0 05:48:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:09 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:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:09 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:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:09 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 0x103ea7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57a5beb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, '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.g47mmQK83Y/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.hXQGHeGMUX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:48:09 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:48:09 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:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:09 INFO - 'HOME': '/Users/cltbld', 05:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:09 INFO - 'LOGNAME': 'cltbld', 05:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:09 INFO - 'MOZ_NO_REMOTE': '1', 05:48:09 INFO - 'NO_EM_RESTART': '1', 05:48:09 INFO - 'PAGER': '/bin/cat', 05:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:09 INFO - 'PWD': '/builds/slave/test', 05:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:09 INFO - 'SHELL': '/bin/bash', 05:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:48:09 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:09 INFO - 'USER': 'cltbld', 05:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:09 INFO - 'XPC_FLAGS': '0x0', 05:48:09 INFO - 'XPC_SERVICE_NAME': '0', 05:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-DY1loL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-HPOQod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ZW06QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-DKsWy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-KK1BhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-NjmmuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-riFQLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-vL1BWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-X04Irv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:10 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-9PSZee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-xqzuvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ZfH9UX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-eCQtgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-hyT_OU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Yk84xG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-ghQIWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:11 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-4iX0dA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:48:11 INFO - Running setup.py install for manifestparser 05:48:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:48:11 INFO - Running setup.py install for mozcrash 05:48:12 INFO - Running setup.py install for mozdebug 05:48:12 INFO - Running setup.py install for mozdevice 05:48:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:48:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:48:12 INFO - Running setup.py install for mozfile 05:48:12 INFO - Running setup.py install for mozhttpd 05:48:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:48:12 INFO - Running setup.py install for mozinfo 05:48:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:48:12 INFO - Running setup.py install for mozInstall 05:48:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:48:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:48:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:48:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:48:12 INFO - Running setup.py install for mozleak 05:48:13 INFO - Running setup.py install for mozlog 05:48:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:48:13 INFO - Running setup.py install for moznetwork 05:48:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:48:13 INFO - Running setup.py install for mozprocess 05:48:13 INFO - Running setup.py install for mozprofile 05:48:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:48:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:48:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:48:13 INFO - Running setup.py install for mozrunner 05:48:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:48:13 INFO - Running setup.py install for mozscreenshot 05:48:14 INFO - Running setup.py install for moztest 05:48:14 INFO - Running setup.py install for mozversion 05:48:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:48:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:48:14 INFO - Cleaning up... 05:48:14 INFO - Return code: 0 05:48:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:14 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 0x103ea7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57a5beb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, '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.g47mmQK83Y/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.hXQGHeGMUX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:48:14 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:48:14 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:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:14 INFO - 'HOME': '/Users/cltbld', 05:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:14 INFO - 'LOGNAME': 'cltbld', 05:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:14 INFO - 'MOZ_NO_REMOTE': '1', 05:48:14 INFO - 'NO_EM_RESTART': '1', 05:48:14 INFO - 'PAGER': '/bin/cat', 05:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:14 INFO - 'PWD': '/builds/slave/test', 05:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:14 INFO - 'SHELL': '/bin/bash', 05:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:48:14 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:14 INFO - 'USER': 'cltbld', 05:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:14 INFO - 'XPC_FLAGS': '0x0', 05:48:14 INFO - 'XPC_SERVICE_NAME': '0', 05:48:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:48:14 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-bUbE70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:48:14 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:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:48:14 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-eoVnzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:48:14 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:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:48:14 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Z5b7SG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:48:14 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:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:48:14 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-tAhNs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:48:15 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:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:48:15 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Vq2aR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:48:15 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:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:48:15 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-AV_vTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:48:15 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:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:48:15 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-AVxpeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:48:15 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:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:48:15 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-FqZ4yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:48:15 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:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:48:15 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-uR713t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:48:15 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:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:48:15 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-QQkiq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:48:15 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:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:48:15 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-Tz7I85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:48:15 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:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:48:15 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-wlE0OT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:48:15 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:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:48:15 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-uq8ec6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:48:16 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:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:48:16 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-kYWhjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:48:16 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:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:16 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-rVcxaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:48:16 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:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:48:16 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-O_Rt1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:48:16 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:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:48:16 INFO - Running setup.py (path:/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/pip-uxTOpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:48:16 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:48:16 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:48:16 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:48:16 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:48:16 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:48:16 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:48:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:48:16 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:48:16 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:48:16 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:48:16 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:48:16 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:48:16 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:48:18 INFO - Downloading blessings-1.6.tar.gz 05:48:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:48:18 INFO - Installing collected packages: blessings 05:48:18 INFO - Running setup.py install for blessings 05:48:19 INFO - Successfully installed blessings 05:48:19 INFO - Cleaning up... 05:48:19 INFO - Return code: 0 05:48:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:48:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:19 INFO - Reading from file tmpfile_stdout 05:48:19 INFO - Current package versions: 05:48:19 INFO - blessings == 1.6 05:48:19 INFO - blobuploader == 1.2.4 05:48:19 INFO - docopt == 0.6.1 05:48:19 INFO - manifestparser == 1.1 05:48:19 INFO - mozInstall == 1.12 05:48:19 INFO - mozcrash == 0.17 05:48:19 INFO - mozdebug == 0.1 05:48:19 INFO - mozdevice == 0.48 05:48:19 INFO - mozfile == 1.2 05:48:19 INFO - mozhttpd == 0.7 05:48:19 INFO - mozinfo == 0.9 05:48:19 INFO - mozleak == 0.1 05:48:19 INFO - mozlog == 3.1 05:48:19 INFO - moznetwork == 0.27 05:48:19 INFO - mozprocess == 0.22 05:48:19 INFO - mozprofile == 0.28 05:48:19 INFO - mozrunner == 6.11 05:48:19 INFO - mozscreenshot == 0.1 05:48:19 INFO - mozsystemmonitor == 0.0 05:48:19 INFO - moztest == 0.7 05:48:19 INFO - mozversion == 1.4 05:48:19 INFO - psutil == 3.1.1 05:48:19 INFO - requests == 1.2.3 05:48:19 INFO - wsgiref == 0.1.2 05:48:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:48:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:19 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 0x103ea7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57a5beb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, '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.g47mmQK83Y/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.hXQGHeGMUX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:48:19 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:48:19 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:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:19 INFO - 'HOME': '/Users/cltbld', 05:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:19 INFO - 'LOGNAME': 'cltbld', 05:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:19 INFO - 'MOZ_NO_REMOTE': '1', 05:48:19 INFO - 'NO_EM_RESTART': '1', 05:48:19 INFO - 'PAGER': '/bin/cat', 05:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:19 INFO - 'PWD': '/builds/slave/test', 05:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:19 INFO - 'SHELL': '/bin/bash', 05:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:48:19 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:19 INFO - 'USER': 'cltbld', 05:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:19 INFO - 'XPC_FLAGS': '0x0', 05:48:19 INFO - 'XPC_SERVICE_NAME': '0', 05:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:19 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:48:19 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:48:19 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:48:19 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:48:19 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:48:19 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:48:19 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:48:19 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:48:19 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:48:19 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:48:19 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:48:19 INFO - Cleaning up... 05:48:19 INFO - Return code: 0 05:48:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:48:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:19 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 0x103ea7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57a5beb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, '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.g47mmQK83Y/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.hXQGHeGMUX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:48:19 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:48:19 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:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:19 INFO - 'HOME': '/Users/cltbld', 05:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:19 INFO - 'LOGNAME': 'cltbld', 05:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:19 INFO - 'MOZ_NO_REMOTE': '1', 05:48:19 INFO - 'NO_EM_RESTART': '1', 05:48:19 INFO - 'PAGER': '/bin/cat', 05:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:19 INFO - 'PWD': '/builds/slave/test', 05:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:19 INFO - 'SHELL': '/bin/bash', 05:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:48:19 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:19 INFO - 'USER': 'cltbld', 05:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:19 INFO - 'XPC_FLAGS': '0x0', 05:48:19 INFO - 'XPC_SERVICE_NAME': '0', 05:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:19 INFO - Downloading/unpacking jsonschema 05:48:19 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:48:19 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:48:19 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:48:19 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:48:19 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:48:19 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:48:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:48:22 INFO - Downloading/unpacking functools32 (from jsonschema) 05:48: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:48: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:48: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:48: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:48: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:48: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:48:22 INFO - Downloading functools32-3.2.3-2.tar.gz 05:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:48:22 INFO - warning: no files found matching '*.txt' 05:48:22 INFO - no previously-included directories found matching 'build' 05:48:22 INFO - no previously-included directories found matching 'dist' 05:48:22 INFO - no previously-included directories found matching '.git*' 05:48:22 INFO - Installing collected packages: jsonschema, functools32 05:48:22 INFO - Running setup.py install for functools32 05:48:23 INFO - warning: no files found matching '*.txt' 05:48:23 INFO - no previously-included directories found matching 'build' 05:48:23 INFO - no previously-included directories found matching 'dist' 05:48:23 INFO - no previously-included directories found matching '.git*' 05:48:23 INFO - Successfully installed jsonschema functools32 05:48:23 INFO - Cleaning up... 05:48:23 INFO - Return code: 0 05:48:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:48:23 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:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:23 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:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:48:23 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 0x103ea7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eb7420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd57a5beb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, '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.g47mmQK83Y/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.hXQGHeGMUX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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:48:23 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:48:23 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:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:23 INFO - 'HOME': '/Users/cltbld', 05:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:23 INFO - 'LOGNAME': 'cltbld', 05:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:23 INFO - 'MOZ_NO_REMOTE': '1', 05:48:23 INFO - 'NO_EM_RESTART': '1', 05:48:23 INFO - 'PAGER': '/bin/cat', 05:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:23 INFO - 'PWD': '/builds/slave/test', 05:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:23 INFO - 'SHELL': '/bin/bash', 05:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:48:23 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:23 INFO - 'USER': 'cltbld', 05:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:23 INFO - 'XPC_FLAGS': '0x0', 05:48:23 INFO - 'XPC_SERVICE_NAME': '0', 05:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:48:23 INFO - Downloading/unpacking flake8 05:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:48:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:48: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:48: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:48: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:48: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:48: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:48: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:48:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:48:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:48:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:48:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:48:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:48:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:48:27 INFO - no previously-included directories found matching 'docs/_build' 05:48:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:48:27 INFO - Running setup.py install for pep8 05:48:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:48:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:48:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:48:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:48:27 INFO - no previously-included directories found matching 'docs/_build' 05:48:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:48:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:48:27 INFO - Cleaning up... 05:48:27 INFO - Return code: 0 05:48:27 INFO - Running post-action listener: _resource_record_post_action 05:48:27 INFO - Running post-action listener: _start_resource_monitoring 05:48:27 INFO - Starting resource monitoring. 05:48:27 INFO - ##### 05:48:27 INFO - ##### Running install step. 05:48:27 INFO - ##### 05:48:27 INFO - Running pre-action listener: _resource_record_pre_action 05:48:27 INFO - Running main action method: install 05:48:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:48:27 INFO - Reading from file tmpfile_stdout 05:48:27 INFO - Detecting whether we're running mozinstall >=1.0... 05:48:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:48:27 INFO - Reading from file tmpfile_stdout 05:48:27 INFO - Output received: 05:48:27 INFO - Usage: mozinstall [options] installer 05:48:27 INFO - Options: 05:48:27 INFO - -h, --help show this help message and exit 05:48:27 INFO - -d DEST, --destination=DEST 05:48:27 INFO - Directory to install application into. [default: 05:48:27 INFO - "/builds/slave/test"] 05:48:27 INFO - --app=APP Application being installed. [default: firefox] 05:48:27 INFO - mkdir: /builds/slave/test/build/application 05:48:27 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:48:27 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:49:01 INFO - Reading from file tmpfile_stdout 05:49:01 INFO - Output received: 05:49:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:49:01 INFO - Running post-action listener: _resource_record_post_action 05:49:01 INFO - ##### 05:49:01 INFO - ##### Running run-tests step. 05:49:01 INFO - ##### 05:49:01 INFO - Running pre-action listener: _resource_record_pre_action 05:49:01 INFO - Running main action method: run_tests 05:49:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:49:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:49:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:49:01 INFO - 2016-04-22 05:49:01.358 screenresolution[1640:7526] starting screenresolution argv=screenresolution get 05:49:01 INFO - 2016-04-22 05:49:01.361 screenresolution[1640:7526] Display 0: 1600x1200x32@60 05:49:01 INFO - 2016-04-22 05:49:01.366 screenresolution[1641:7528] starting screenresolution argv=screenresolution list 05:49:01 INFO - Available Modes on Display 0 05:49:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:49:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:49:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:49:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:49:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:49:01 INFO - Intel Iris: 05:49:01 INFO - Chipset Model: Intel Iris 05:49:01 INFO - Type: GPU 05:49:01 INFO - Bus: Built-In 05:49:01 INFO - VRAM (Dynamic, Max): 1536 MB 05:49:01 INFO - Vendor: Intel (0x8086) 05:49:01 INFO - Device ID: 0x0a2e 05:49:01 INFO - Revision ID: 0x0009 05:49:01 INFO - Displays: 05:49:01 INFO - Display: 05:49:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:49:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:49:01 INFO - Display Serial Number: 1600x1200 60 05:49:01 INFO - Main Display: Yes 05:49:01 INFO - Mirror: Off 05:49:01 INFO - Online: Yes 05:49:01 INFO - Rotation: Supported 05:49:01 INFO - Return code: 0 05:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:49:01 INFO - Python 2.7.3 05:49:01 INFO - Return code: 0 05:49:01 INFO - grabbing minidump binary from tooltool 05:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:49:01 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 0x103d46e00>, '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 0x103b6fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d41b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:49:01 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:49:01 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:49:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:49:01 INFO - Return code: 0 05:49:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:49:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:49:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:49:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:49:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:49:02 INFO - Return code: 0 05:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0175 --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:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g47mmQK83Y/Render', 05:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:02 INFO - 'HOME': '/Users/cltbld', 05:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:02 INFO - 'LOGNAME': 'cltbld', 05:49:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:49:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:02 INFO - 'MOZ_NO_REMOTE': '1', 05:49:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:49:02 INFO - 'NO_EM_RESTART': '1', 05:49:02 INFO - 'PAGER': '/bin/cat', 05:49: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:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:02 INFO - 'PWD': '/builds/slave/test', 05:49:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:02 INFO - 'SHELL': '/bin/bash', 05:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hXQGHeGMUX/Listeners', 05:49:02 INFO - 'TMPDIR': '/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/', 05:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:02 INFO - 'USER': 'cltbld', 05:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:02 INFO - 'XPC_FLAGS': '0x0', 05:49:02 INFO - 'XPC_SERVICE_NAME': '0', 05:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0175', '--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:49:03 INFO - mozversion application_buildid: 20160419230832 05:49:03 INFO - mozversion application_changeset: d42b25947fc26a0f9eab98d631465b72769553bd 05:49:03 INFO - mozversion application_display_name: Nightly 05:49:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:49:03 INFO - mozversion application_name: Firefox 05:49:03 INFO - mozversion application_remotingname: firefox 05:49:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:49:03 INFO - mozversion application_vendor: Mozilla 05:49:03 INFO - mozversion application_version: 48.0a1 05:49:03 INFO - mozversion platform_buildid: 20160419230832 05:49:03 INFO - mozversion platform_changeset: d42b25947fc26a0f9eab98d631465b72769553bd 05:49:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:49:03 INFO - mozversion platform_version: 48.0a1 05:49:03 INFO - using testdate: 1461329343 05:49:03 INFO - actual date: 1461329343 05:49:03 INFO - starting webserver on 'localhost:49281' 05:49:03 INFO - SUITE-START | Running 2 tests 05:49:03 INFO - TEST-START | dromaeo_css 05:49:03 INFO - operating with platform_type : mac_ 05:49:03 INFO - Initialising browser for dromaeo_css test... 05:49:03 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpnE23_r/profile http://localhost:49281/getInfo.html) 05:49:05 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 05:49:05 INFO - PROCESS | 1659 | colorDepth:24 05:49:05 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 05:49:05 INFO - PROCESS | 1659 | __metrics 05:49:05 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:49:05 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:49:06 INFO - TEST-INFO | 1659: exit 0 05:49:06 INFO - Browser initialized. 05:49:06 INFO - Running cycle 1/1 for dromaeo_css test... 05:49:06 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmpnE23_r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:18 INFO - PROCESS | 1661 | RSS: Main: 237887488 05:49:18 INFO - PROCESS | 1661 | 05:50:09 INFO - PROCESS | 1661 | 1461329409934 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:52:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 05:52:21 INFO - PROCESS | 1661 | RSS: Main: 374206464 05:52:21 INFO - PROCESS | 1661 | 05:55:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 05:55:25 INFO - PROCESS | 1661 | RSS: Main: 381255680 05:55:25 INFO - PROCESS | 1661 | 05:58:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 05:58:28 INFO - PROCESS | 1661 | RSS: Main: 374845440 05:58:28 INFO - PROCESS | 1661 | 06:01:30 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 06:01:30 INFO - PROCESS | 1661 | RSS: Main: 375771136 06:01:30 INFO - PROCESS | 1661 | 06:04:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 06:04:34 INFO - PROCESS | 1661 | RSS: Main: 346537984 06:04:34 INFO - PROCESS | 1661 | 06:07:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 06:07:37 INFO - PROCESS | 1661 | RSS: Main: 383766528 06:07:37 INFO - PROCESS | 1661 | 06:07:37 INFO - PROCESS | 1661 | __start_tp_report 06:07:37 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 06:07:37 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 06:07:37 INFO - PROCESS | 1661 | |i|pagename|runs| 06:07:37 INFO - PROCESS | 1661 | |0;dojo.html;3405.5913290405147;3456.879009234676;3477.200243943894;3478.2521757822065;3524.629913859045;5627.2684551008415;5686.2039314496005;5718.342390625079;5723.170140329655;5730.512906402955;5704.828855134342;5737.540996720263;5795.768169273224;5833.737481813318;5849.795257166;5099.286099946011;5135.5634771044415;5158.613104017195;5167.044096842077;5259.947400525984;5220.504052115043;5271.2356708277275;5272.657277276965;5282.7622756976025;5318;5117.155669314578;5195.19474481456;5199.610029247802;5201.349831271091;5208.166693329087;4317.935230971538;5133.4609865964285;5168.664036837594;5172.741362931838;5218.76515556797;5112.667676601046;5114.514121158484;5125.641205115643;5162.483751624845;5218.530332270114;5539.584531160139;5582.49757521825;5614.101743721026;5624.128260119647;5644.802431914904;6148.938510614926;6174.536909731743;6192.659403732793;6194.721237544321;6234.781782637586;6207.534434917399;6229.221347331583;6275.152854364648;6276.560640755149;6283.183186185813;5686.061799803069;5812.070068788974;5857.970710146465;5863.091220860773;5863.853403664943;6178.814635560941;6244.219472565929;6248.781292654735;6256.624602523863;6259.436650701458;6124;6156.907646385308;6170.753169873155;6174.938250617526;6182.474489668429;6112.746658179578;6157.876842463125;6200;6248.50011999039;6289.811305660837;6147.877042459215;6209.19280493542;6244.781432649836;6254.655993920378;6266.436020758062;6251.749930002749;6280.7487700491465;6289.371062893765;6293.653849038347;6295.244570651551;4830.468648448667;4929.950700492949;4934.703917764874;4956.058348913695;4992.8751781205765;4452.198604251266;4455.977720111378;4464.575865292792;4497.280435130363;4498.482674492409;5717.285339332583;5719.428057194247;5720.914186287209;5723.313202415737;5740.052891272893;6120.569035656853;6130.275842464065;6173.536984726164;6186.938130618726;6221.06683997395;5807.564432667482;5814.127880817827;5818.854528636733;5820.447057529613;5827.271591051119;5398.73006349681;5414.40441551429;5421.051316019681;5426.186072089218;5430.5927055471;6146.7233974471255;6150.569460137747;6183.690815459209;6240.875182496416;6293.622382657055;6177.320494745664;6198.845028874134;6208.503319734501;6209.472194863487;6221.657808820558;6199.535034872493;6199.690015499297;6210.968945155245;6215.285242197205;6221.09794079865;6073.240844894388;6132.141360525334;6139.584134579286;6169.043798211284;6193.81418557444;6141.815745527641;6151.292601350902;6172.043333283169;6186.721597528212;6198.039303907902;4817.686850354716;5975.342712301556;5982.0727787193055;6022.5784195105925;6051.364606716232;6080.0575910733905;6083.361247068994;6106.582013105306;6140.263168419639;6151.8926593213655;5288.968651112996;5720.628159169641;5960.374160713062;6001.7599296027665;6030.758769649165;5966.880662386641;6110.663913484801;6117.724702388314;6146.309764814533;6151;5023.572996295356;5928.67392293428;5947.137665038459;6030.125631783455;6069.514438844793 06:07:37 INFO - PROCESS | 1661 | |1;ext.html;507.81210951947764;538.0234873704227;575.576950941058;581.7294957844601;616.7532986805277;24139.982502187228;30553.083407497754;38393.04017399563;39405;39584.20831583367;42624.57375426245;43404.56595434053;43407.56592434075;43509.782451087776;43716;9587.089226523469;9850.310478266525;9854.260930430211;9864.654737084204;9888.307818452711;9458.57436415363;9611.471369074698;9619.989901060393;9626.903730962671;9633.084856938614;8667.263282620985;9356.672516461926;9540.761480962961;9641.807163856718;9644;9530.904690953072;9599.32804703671;9602.66390676323;9602.6639067633;9603.711888643387;8947.865782013276;9653.662121825702;9662.75843103921;9681.983391743906;9691.273154513368;40497.79751101255;40952.180956380704;41039;41070.79464602658;41079.1784164315;3227.580414546094;3312.8840490582957;3316.6849149330774;3460.6471717868503;3849.268638958589;29051.709482905113;29422.558661620093;30434.93477728291;30460.695393046008;30487.69512304893;2806.6211061506638;2808.859557022161;2826.364068084685;2832.235296469962;2911.9854400728073;447287;447573;448311;448768.75615621713;450180.7382520126;59842.40157598456;60440;60459;60536;60555;63599;63668.363316366245;63705.36294636994;63708;63897.36102639007;14868.479603213835;16733;16755.32978680839;16776.832231677527;16781.160941952854;11495.482703278372;11607.825882611767;11616.954474097203;11619.941900290445;12707.68230794219;14576.708465830625;15739.842601574066;15903;15941.36234550605;15948.963317384332;4614.618751526356;7590.696372145163;7900.525968441912;8074.475159114639;8096.271335579826;960.2030314838668;978.2467500025083;1020.3520764314713;1082.9729256768487;1142.2004596782285;29945.520190306484;30041;30051.849740751593;30341.241468963453;30474.69525304763;1111.1555218034352;1129.6839182352467;1168.1355796710372;1191.7497325561555;1234.7777400067894;14910.254487275593;15510.457134000257;15687.529374118794;15899.889584100212;16192.595185120466;642014;643911;648739;649137;650298;61104;62030.68984655048;62071.6896415515;62103;62205.06692399618;1250.6623211732963;1324.317976242236;1372.0190064104013;1505.0292561297902;1518.764591488321;26460;28903.421931561672;28937;29507.262318441353;29713.108606741833;45822.77088614669;46184;46713;46891;46923.59229223129;359303;359609;360255;360564;364704;33396.49905251375;38912.80543597377;40083.39874901937;40808.38787418132;40842.79578602088;42190.789046054575;43057.124858762865;43448.56551434445;43765.781171095216;44192.55807441884;36300.81849590735;38317.80841095777;39054.414183787834;39566.604333957446;39957.80021099876;35511.46732798959;35542.64457355497;35909.64090359167;36190.63809361873;36372.63627363693;35076.416833509364;35315.47026794651;35829.46255806113;35924.64075359317;36749;30827.218012037316;31956.20109497281;32032.51951220687;33320.66679333176;33458.66541334555;20103.899480502503;24389.878050609634;24612.956711081097;24892.37769055788;25224.74775252224 06:07:37 INFO - PROCESS | 1661 | |2;jquery.html;393.1723721566103;393.4884649955056;395.95446523649787;396.77185618269493;408.3629537920844;14878.55364339069;14954.252287385641;16441.26014329355;19047.52381190469;20026.99999999998;19452.51368715783;19669.901650491764;20340.796592034076;21331.040103195355;21391.465213369673;5667.518260947814;5742.569307301948;5830.242068531085;5835.328937172237;5877.588568800185;4049.8177582008734;5620.57845661577;5796.913046304308;5829.5919285650025;5844.064949608063;6013.1581578579035;6331.5884467509695;6358.395952384516;6362.586431881936;6368.235811702579;6267.592606480587;6347.714352854109;6353.618782873019;6354.809355719336;6364.236291645032;5361;5523.585731070146;5542.0855558832745;5542.224088627595;5544.168374743821;24588;25578.48843023148;25753.227403177934;25753.742462575323;26098.478030439474;2111.2716112941166;2174.7716489768663;2359.693239878805;2430.4045508850268;2525.9621105683427;9982.001799820033;12272.263664180178;13616.93191534036;14134;15138.318775655121;1681.512361415188;1707.4206359117766;1733.7399390091587;1738.2351765223261;1742.3988723890363;84374;113856.43071784671;114800;114885;115452.84547154505;9579.425234485952;11469.885301146882;11581.478833452515;11652.53389864413;11666.008389287006;14980.250987450583;15084.321205545777;15092.924535377473;15111.4710985115;15139.015963962307;8377.455465394853;8543.871841922377;8565.357598180162;8676.91323086761;8766.649334026695;6902.240753517109;8424.368172386972;8477.86319061951;8545.957270213734;8614.483131012033;4499.482559505634;4596.7931443085145;4603.915376697621;4651.534846515389;4663.323818046432;1882.802305757903;1944.8346890514465;1950.3173889138689;1951.5511432370633;1957.3051566693903;884.540039179623;959.0936564946059;973.1582181413027;976.2239019979133;1042.1276109124983;19313.90343048276;20051.899740501205;20063.59872802565;20239.696404553943;20374.388768336972;943.0899182288987;956.7082039977873;964.2952807071449;994.4878387630376;1060.6075751971666;10284.382937023802;10528.210384221215;10529.473526323653;10537.209709271836;10540.420276884844;557008;604065;608958;618346;618535;130435.34782326217;135830.3208483971;139945;142511;143631;883.9248663863515;999.6900960702205;1043.6816770884952;1044.0916402729522;1054.7626783973699;17779.73330400019;18011.2795488179;18045.819541804412;18089.909550452165;18266.634667306844;42337.78831105825;51238;51618.74190629173;51634.74182629063;52590.73704631453;72767.27232727605;75150;76666.23333766813;77185.22814772004;79493.80759288731;7589.1651918290045;7883.172266911892;7969.362451004022;8014.517314296873;8335.874961875455;8999.145081217273;11650;12166.330851802883;12377.752444951231;12576;10370.377777333384;10683.358998460117;10755.677329680122;10817.51321190564;11430.371329576954;10814.89185108139;10845.674629761119;11005.1746119043;11160.49777760019;11410.600628977945;10914.945425272823;11173;11485.540578376771;11611.76776464449;11642.70893227676;8072.192780721881;8128.918710812816;8293.792655183668;8295.04606970182;8426.747197584082;10009.749756256151;11075;11372.374519401512;11389.601363952223;11417.828732568853 06:07:37 INFO - PROCESS | 1661 | |3;mootools.html;1421.502474134054;1614.8223695393506;1617.5794293483693;1883.3973128598855;1995.2517805822827;7823.687052517899;8518.97772267328;8756.824863502727;8764.824703505941;8767.561621918903;8349.165083491647;8585.356098292637;8589.699360522383;8590.140985901407;8591.097934716843;1957.696557033655;2247.43814046488;2282.2696737044153;2283.5432913417385;2291.32405940248;2368.538135063663;2403.639454081884;2409.915652952147;2416.4200591858003;2422.757724227572;2379.1435083369975;2401.0875867170453;2405.3265085775924;2411.1560953666253;2415.5531226723024;2371.087131454385;2387.4270175157926;2393.198278576679;2396.376941995085;2403.7596240375997;2338.3335749311364;2366.810655147601;2370.5377451396985;2371.5968285391523;2372.41875740443;8482.36382271326;8514.957425212897;8668.653253869837;8675.045744968049;8688.78278043054;5156.1750119980625;5344.5724342052545;5348.090824559834;5356.839294821161;5359.598030147716;3432.2105915639295;3462.9653703462895;3522.4892390603477;3552.0231936217747;3567.8751243706347;1029.233221250166;1043.1394099867564;1043.519980808829;1051.7265510967127;1052.9105026072793;11353.318800871892;11610.013148882355;11611.419429028514;11620.418979051097;11621.128415368881;4365.978170109129;4368.7815609219415;4605.861824145281;4609.985803123308;4615.676902616851;9048.954755226314;9053.90946090544;9059.547022648841;9063.54682265884;9092.81814363709;4457.598816106566;5135.97432012845;5155.072087024297;5157.974210128926;5165.896682066413;4299.591538803808;4490.034642551802;4665.580097791215;4854.781534830941;4859.125357435625;3773.6981041516615;3775.905602359963;3853.1523064925673;3873.2447172801035;3886.9222615547533;1205.8673545909942;1216.6714986953473;1217.2818037358002;1226.1539537718945;1229.342301868514;651.3063587279532;771.4715419937248;918.5912269040213;925.1396201532505;929.4051806843626;4846.5638092571835;5546.278983732089;5568.582356323292;5702.800401985911;5815.244018277597;987.5457289646699;1038.4703801061512;1069.3370110531364;1072.088724584113;1072.3083611070815;2902.8693708783157;3870.2066076454257;3894.5131858517684;3922.5292964844402;3929.155231625214;10267.537960791633;10542.314749541256;10583.100436462986;10593.364398136137;10751.193660475496;8674.522901240367;8818.955905220433;10448.582056717647;10481.423521706227;10500.422476763852;7680.807979800438;7872.212778722082;7984.960075199587;8041.959790201012;8129.75610731679;5061.215511595708;5849.151872978479;5861.531077513703;5931.991561434653;5990.880182396415;9727.562259698472;9826.361286516354;9837.114659680663;9852.310338276251;10012.799744004933;9697.418154910445;9912.107910288109;9929.702108936743;9975.551100200653;10002.8499572505;7362.726508104781;7372.778816635509;7415.8887616684715;7499.437542184467;7524.473286870086;8041.954545908994;8175.509469431853;8177.223163799429;8182.549959752033;8193.50838949665;6084.330723620486;7210.783676489714;7249.238829922782;7466;7508.962455187689;6449.258335291502;6477.190351206099;6906.205786334435;7139.143302803489;7308.940203670331;5295.258663786996;6803.353681400259;6807.5575087619145;6808.625525595941;6811.9659401702675;4614.953850461587;4687.9296810547185;4703.365045718784;4745.525447455227;4826.300186473012;4832.879178020437;4880.389951256093;4928.704277743347;4950.801967921387;4994.300797888372 06:07:38 INFO - PROCESS | 1661 | |4;prototype.html;310.12389998254923;318.20448877805484;325.22271770468586;328.3285680782799;330.3838341493116;5500;5683.3464151622575;5719.828405147847;5767.30792304924;5795.130730390439;5296.285001524793;5346.518813306801;5502.9174562381495;5623.297087864017;5922.674252916088;4321.373400856874;4326.6538676905775;4331.1554246921705;4332.393464914914;4340.437442520698;4155.459790227266;4326.935095973563;4334.154839806239;4348.9782551087355;4384.49578298495;4289.227938970999;4303.332983387564;4305.526392096866;4309.202797482476;4329.112531930962;3704.481372607837;4222.880936551816;4233.851815186485;4328.84849030284;4345.891352716191;3182.3953448844645;3264.722498587623;3772.2270532849516;3774.641409066134;3779.130799916035;4787.377640906695;5134.024535338273;5495.313085864267;5508.3114610673665;5567.610267281292;4885.60915126789;4904.656674032819;4916.852494425138;4923.046172296629;4947.678400903929;5828.513728999078;5986.371430999726;6032.819015429544;6048.667323297217;6090.558834258674;3455.706264967481;4069.5930406959365;4079.2249472600406;4081.1837632473625;4111.568285330027;5520.065929126165;6073.1497590337385;6261.87476250477;6365.872682546369;6393.456556192729;3840.2895464339185;4123.567025462343;4128.752274863517;4130.6661736118995;4145.150244199932;3692.5015122958057;4159;4180.853670121531;4208.431861698693;4211.241976444208;4086.6730661547003;4902.2646603009125;4905.950940490621;4920.827771027997;4968.478309777422;5063.240513922868;5774.04728219839;5846.29844418664;6081.878362432726;6108.664023478751;1233.0695216779443;1327.3097989045643;1440.9351579178963;1448.3120517753982;1449.145004447381;793.3890904003885;825.8141918068507;923.882102655794;961.6489981156827;968.080323692495;4670.929936050962;5356.5982551308025;5423.945760542423;5605.271314729059;5669.886602267931;6102.805652262957;6171.444569988723;6253.312135665074;6290;6301.653409062454;4174.415581818548;4702.811887524529;5609.214709940612;5624.690642014729;5689.6586204827845;2940.9559606339867;3269.476883698597;3297.5383446317533;3320.5185248138882;3322.534845121685;5283.339582552181;6164.722587483574;6524.184476940382;6589.769358072444;6624.238212605515;5709.401367617073;5889.499392551681;6315.936840631535;6372.2671892732005;6439.908843755024;5334.093204155303;5340.786368545215;5564.276644036249;6270.7491700331475;6480.269403335591;5152.562032227303;5203.583713302851;5980.5514586405325;6355.205599300088;6368.363163683594;6251.156093927261;6263.6241825490615;6275.341089185752;6275.466585340115;6341.936580634135;5730.426957304403;5761.366249712444;6231.53263505248;6582.597351966593;6588.209414870246;3378.831058447117;3876.748011379364;3998.9600103998587;4109;4138.8344466221015;5718.370979192368;6208.410201030895;6220.875582488235;6266.968665156827;6274.121622972696;5752.28096487939;5869.266341707286;6067.696615169312;6082.574219804571;6086.482648974887;5237.16205407133;5974.492168165754;6031.155638210742;6051.33435322123;6060.303065147565;5861.296644402699;5863.560232982458;5888.852778680567;5941.316748573797;5973.492253158356;5693.946619875336;6022.66875321844;6033.1644127260115;6160.537959653134;6203.348648391854;5952.970235148797;5997.940020599738;6012.218411606463;6029.78895738647;6035.426634469718 06:07:38 INFO - PROCESS | 1661 | |5;yui.html;643.4016364780754;687.3504538211389;724.7101159536185;747.3722073458293;747.700919632147;14855.851441485582;14872;15312.157831319273;15317.770233446507;15925.681486370295;14535.055221410626;14565.053271537368;14567.927160364183;14584.562463126096;14608.415663373478;725.5211560370157;753.6043577122;913.1690161952624;936.0966667166183;936.9786932243853;912.3795818843184;955.961761529538;956.0248546482584;957.9185769209626;958.1568219966455;990.3117333453253;991;991.3110388280148;991.7024892532247;993.7416271769356;762.3177256355586;958.4153666263643;985.6057576969198;986.0878687214334;992.483908367652;736.7126820539994;740.6185814305637;742.7734540965004;743.2418932688628;744.0922075068407;14948.626284342978;14990.850091499055;15025.549233523012;15026.474073407488;15029.473968411163;12077;12155.878441215564;12269.447874845653;12299.077569182258;12373.010159187243;7781.8832717509285;9965.75085622858;10052.849207261897;10177.796444071077;10400.843987340197;3498.405271103893;3542.3800834854055;3547.343741407848;3625.111847597329;3628.0567052566257;86312;86993.56503217507;88063.55968220181;88204.55897720471;89198;9277.443353398818;9381.437113773196;9494.762630934281;9602.95198524003;10054;21136;21359.67960480594;21447.249346273118;21462.892685536473;21468;7618.885716714254;7646.923530764621;7697.076350837935;7714.074311082706;7865.685372585032;4070.633642972147;4118.052847844952;4136.048708796993;4136.462259906193;4137.172565486914;3556.6976806971697;3639.217568222845;3643.690286325639;3647.2340808430527;3648.325059863934;629.8488362792897;644.1947565543071;644.7195469970578;653.6764301670703;661.2329697550817;524.048851334826;534.2253732088487;534.5940177333665;551.4926267833603;581.5289615411446;7622.885656715153;7885.684572617031;7916.802079948047;8022.719204827919;8441.155884411632;561.9213310136585;572.9398413166641;580.5299932254994;595.7200115945499;601.4767152577199;3740.551133863954;3746.1571146492365;3761.8119094045187;3847.115163512408;3847.3844184930836;75133.12167195864;78835.81746273811;84049.57975210085;87323.56338218268;88672.11327886768;65318.346816532176;72275;73238.2676173242;73506.89739653909;73728.15679608053;65550.67224663941;67328.6633566829;68208;70150;72384.63807680928;7530.322270995636;7533.2466753324225;7543.057117860268;7546.358559522282;7583.393328533814;39618.60381396149;41148.588514114475;42174.7891260554;42467;42635.57364426316;60123.69938150281;61065;61118.694406527684;61390;63197.68401157965;9944.552495137592;10032.247581431568;10045.69862904114;10070.14403775658;10292.176625870063;9762.316637835283;10755.516001780095;10918.617848375268;11196.94401527987;11346;10354.637587684394;10392.740181495523;10447.320924139907;10687.358758474516;10696.786064278827;10058.094771470604;10159.034891685573;10285.074343309137;10489.737756556147;10507.106895913934;9356.4386136832;9669.984651611761;10354.792904141725;10554;10801.891981080089;8826.646934122564;8849.69026084084;9017.639294428149;9129.360944733804;9292.58183381763;8787.34094942869;8814.823703526023;8855.512946787989;9050.909490905007;9086.500242486727 06:07:38 INFO - PROCESS | 1661 | __end_tp_report 06:07:38 INFO - PROCESS | 1661 | __start_cc_report 06:07:38 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1262 06:07:38 INFO - PROCESS | 1661 | __end_cc_report 06:07:38 INFO - PROCESS | 1661 | __startTimestamp1461330457989__endTimestamp 06:07:38 INFO - PROCESS | 1661 | 06:07:38 INFO - PROCESS | 1661 | ------- Summary: start ------- 06:07:38 INFO - PROCESS | 1661 | Number of tests: 6 06:07:38 INFO - PROCESS | 1661 | 06:07:38 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5718.99 Median:5966.88 stddev:629.70 (10.6%) stddev-sans-first:604.92 06:07:38 INFO - PROCESS | 1661 | Values: 3405.6 3456.9 3477.2 3478.3 3524.6 5627.3 5686.2 5718.3 5723.2 5730.5 5704.8 5737.5 5795.8 5833.7 5849.8 5099.3 5135.6 5158.6 5167.0 5259.9 5220.5 5271.2 5272.7 5282.8 5318.0 5117.2 5195.2 5199.6 5201.3 5208.2 4317.9 5133.5 5168.7 5172.7 5218.8 5112.7 5114.5 5125.6 5162.5 5218.5 5539.6 5582.5 5614.1 5624.1 5644.8 6148.9 6174.5 6192.7 6194.7 6234.8 6207.5 6229.2 6275.2 6276.6 6283.2 5686.1 5812.1 5858.0 5863.1 5863.9 6178.8 6244.2 6248.8 6256.6 6259.4 6124.0 6156.9 6170.8 6174.9 6182.5 6112.7 6157.9 6200.0 6248.5 6289.8 6147.9 6209.2 6244.8 6254.7 6266.4 6251.7 6280.7 6289.4 6293.7 6295.2 4830.5 4930.0 4934.7 4956.1 4992.9 4452.2 4456.0 4464.6 4497.3 4498.5 5717.3 5719.4 5720.9 5723.3 5740.1 6120.6 6130.3 6173.5 6186.9 6221.1 5807.6 5814.1 5818.9 5820.4 5827.3 5398.7 5414.4 5421.1 5426.2 5430.6 6146.7 6150.6 6183.7 6240.9 6293.6 6177.3 6198.8 6208.5 6209.5 6221.7 6199.5 6199.7 6211.0 6215.3 6221.1 6073.2 6132.1 6139.6 6169.0 6193.8 6141.8 6151.3 6172.0 6186.7 6198.0 4817.7 5975.3 5982.1 6022.6 6051.4 6080.1 6083.4 6106.6 6140.3 6151.9 5289.0 5720.6 5960.4 6001.8 6030.8 5966.9 6110.7 6117.7 6146.3 6151.0 5023.6 5928.7 5947.1 6030.1 6069.5 06:07:38 INFO - PROCESS | 1661 | 06:07:38 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:63049.98 Median:39229.71 stddev:133519.42 (340.4%) stddev-sans-first:133811.40 06:07:38 INFO - PROCESS | 1661 | Values: 507.8 538.0 575.6 581.7 616.8 24140.0 30553.1 38393.0 39405.0 39584.2 42624.6 43404.6 43407.6 43509.8 43716.0 9587.1 9850.3 9854.3 9864.7 9888.3 9458.6 9611.5 9620.0 9626.9 9633.1 8667.3 9356.7 9540.8 9641.8 9644.0 9530.9 9599.3 9602.7 9602.7 9603.7 8947.9 9653.7 9662.8 9682.0 9691.3 40497.8 40952.2 41039.0 41070.8 41079.2 3227.6 3312.9 3316.7 3460.6 3849.3 29051.7 29422.6 30434.9 30460.7 30487.7 2806.6 2808.9 2826.4 2832.2 2912.0 447287.0 447573.0 448311.0 448768.8 450180.7 59842.4 60440.0 60459.0 60536.0 60555.0 63599.0 63668.4 63705.4 63708.0 63897.4 14868.5 16733.0 16755.3 16776.8 16781.2 11495.5 11607.8 11617.0 11619.9 12707.7 14576.7 15739.8 15903.0 15941.4 15949.0 4614.6 7590.7 7900.5 8074.5 8096.3 960.2 978.2 1020.4 1083.0 1142.2 29945.5 30041.0 30051.8 30341.2 30474.7 1111.2 1129.7 1168.1 1191.7 1234.8 14910.3 15510.5 15687.5 15899.9 16192.6 642014.0 643911.0 648739.0 649137.0 650298.0 61104.0 62030.7 62071.7 62103.0 62205.1 1250.7 1324.3 1372.0 1505.0 1518.8 26460.0 28903.4 28937.0 29507.3 29713.1 45822.8 46184.0 46713.0 46891.0 46923.6 359303.0 359609.0 360255.0 360564.0 364704.0 33396.5 38912.8 40083.4 40808.4 40842.8 42190.8 43057.1 43448.6 43765.8 44192.6 36300.8 38317.8 39054.4 39566.6 39957.8 35511.5 35542.6 35909.6 36190.6 36372.6 35076.4 35315.5 35829.5 35924.6 36749.0 30827.2 31956.2 32032.5 33320.7 33458.7 20103.9 24389.9 24613.0 24892.4 25224.7 06:07:38 INFO - PROCESS | 1661 | 06:07:38 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:34934.69 Median:13556.98 stddev:100496.53 (741.3%) stddev-sans-first:100744.98 06:07:38 INFO - PROCESS | 1661 | Values: 393.2 393.5 396.0 396.8 408.4 14878.6 14954.3 16441.3 19047.5 20027.0 19452.5 19669.9 20340.8 21331.0 21391.5 5667.5 5742.6 5830.2 5835.3 5877.6 4049.8 5620.6 5796.9 5829.6 5844.1 6013.2 6331.6 6358.4 6362.6 6368.2 6267.6 6347.7 6353.6 6354.8 6364.2 5361.0 5523.6 5542.1 5542.2 5544.2 24588.0 25578.5 25753.2 25753.7 26098.5 2111.3 2174.8 2359.7 2430.4 2526.0 9982.0 12272.3 13616.9 14134.0 15138.3 1681.5 1707.4 1733.7 1738.2 1742.4 84374.0 113856.4 114800.0 114885.0 115452.8 9579.4 11469.9 11581.5 11652.5 11666.0 14980.3 15084.3 15092.9 15111.5 15139.0 8377.5 8543.9 8565.4 8676.9 8766.6 6902.2 8424.4 8477.9 8546.0 8614.5 4499.5 4596.8 4603.9 4651.5 4663.3 1882.8 1944.8 1950.3 1951.6 1957.3 884.5 959.1 973.2 976.2 1042.1 19313.9 20051.9 20063.6 20239.7 20374.4 943.1 956.7 964.3 994.5 1060.6 10284.4 10528.2 10529.5 10537.2 10540.4 557008.0 604065.0 608958.0 618346.0 618535.0 130435.3 135830.3 139945.0 142511.0 143631.0 883.9 999.7 1043.7 1044.1 1054.8 17779.7 18011.3 18045.8 18089.9 18266.6 42337.8 51238.0 51618.7 51634.7 52590.7 72767.3 75150.0 76666.2 77185.2 79493.8 7589.2 7883.2 7969.4 8014.5 8335.9 8999.1 11650.0 12166.3 12377.8 12576.0 10370.4 10683.4 10755.7 10817.5 11430.4 10814.9 10845.7 11005.2 11160.5 11410.6 10914.9 11173.0 11485.5 11611.8 11642.7 8072.2 8128.9 8293.8 8295.0 8426.7 10009.7 11075.0 11372.4 11389.6 11417.8 06:07:38 INFO - PROCESS | 1661 | 06:07:38 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5538.91 Median:4856.95 stddev:3085.05 (63.5%) stddev-sans-first:3078.19 06:07:38 INFO - PROCESS | 1661 | Values: 1421.5 1614.8 1617.6 1883.4 1995.3 7823.7 8519.0 8756.8 8764.8 8767.6 8349.2 8585.4 8589.7 8590.1 8591.1 1957.7 2247.4 2282.3 2283.5 2291.3 2368.5 2403.6 2409.9 2416.4 2422.8 2379.1 2401.1 2405.3 2411.2 2415.6 2371.1 2387.4 2393.2 2396.4 2403.8 2338.3 2366.8 2370.5 2371.6 2372.4 8482.4 8515.0 8668.7 8675.0 8688.8 5156.2 5344.6 5348.1 5356.8 5359.6 3432.2 3463.0 3522.5 3552.0 3567.9 1029.2 1043.1 1043.5 1051.7 1052.9 11353.3 11610.0 11611.4 11620.4 11621.1 4366.0 4368.8 4605.9 4610.0 4615.7 9049.0 9053.9 9059.5 9063.5 9092.8 4457.6 5136.0 5155.1 5158.0 5165.9 4299.6 4490.0 4665.6 4854.8 4859.1 3773.7 3775.9 3853.2 3873.2 3886.9 1205.9 1216.7 1217.3 1226.2 1229.3 651.3 771.5 918.6 925.1 929.4 4846.6 5546.3 5568.6 5702.8 5815.2 987.5 1038.5 1069.3 1072.1 1072.3 2902.9 3870.2 3894.5 3922.5 3929.2 10267.5 10542.3 10583.1 10593.4 10751.2 8674.5 8819.0 10448.6 10481.4 10500.4 7680.8 7872.2 7985.0 8042.0 8129.8 5061.2 5849.2 5861.5 5932.0 5990.9 9727.6 9826.4 9837.1 9852.3 10012.8 9697.4 9912.1 9929.7 9975.6 10002.8 7362.7 7372.8 7415.9 7499.4 7524.5 8042.0 8175.5 8177.2 8182.5 8193.5 6084.3 7210.8 7249.2 7466.0 7509.0 6449.3 6477.2 6906.2 7139.1 7308.9 5295.3 6803.4 6807.6 6808.6 6812.0 4615.0 4687.9 4703.4 4745.5 4826.3 4832.9 4880.4 4928.7 4950.8 4994.3 06:07:38 INFO - PROCESS | 1661 | 06:07:38 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4880.97 Median:5542.17 stddev:1528.95 (27.6%) stddev-sans-first:1494.25 06:07:38 INFO - PROCESS | 1661 | Values: 310.1 318.2 325.2 328.3 330.4 5500.0 5683.3 5719.8 5767.3 5795.1 5296.3 5346.5 5502.9 5623.3 5922.7 4321.4 4326.7 4331.2 4332.4 4340.4 4155.5 4326.9 4334.2 4349.0 4384.5 4289.2 4303.3 4305.5 4309.2 4329.1 3704.5 4222.9 4233.9 4328.8 4345.9 3182.4 3264.7 3772.2 3774.6 3779.1 4787.4 5134.0 5495.3 5508.3 5567.6 4885.6 4904.7 4916.9 4923.0 4947.7 5828.5 5986.4 6032.8 6048.7 6090.6 3455.7 4069.6 4079.2 4081.2 4111.6 5520.1 6073.1 6261.9 6365.9 6393.5 3840.3 4123.6 4128.8 4130.7 4145.2 3692.5 4159.0 4180.9 4208.4 4211.2 4086.7 4902.3 4906.0 4920.8 4968.5 5063.2 5774.0 5846.3 6081.9 6108.7 1233.1 1327.3 1440.9 1448.3 1449.1 793.4 825.8 923.9 961.6 968.1 4670.9 5356.6 5423.9 5605.3 5669.9 6102.8 6171.4 6253.3 6290.0 6301.7 4174.4 4702.8 5609.2 5624.7 5689.7 2941.0 3269.5 3297.5 3320.5 3322.5 5283.3 6164.7 6524.2 6589.8 6624.2 5709.4 5889.5 6315.9 6372.3 6439.9 5334.1 5340.8 5564.3 6270.7 6480.3 5152.6 5203.6 5980.6 6355.2 6368.4 6251.2 6263.6 6275.3 6275.5 6341.9 5730.4 5761.4 6231.5 6582.6 6588.2 3378.8 3876.7 3999.0 4109.0 4138.8 5718.4 6208.4 6220.9 6267.0 6274.1 5752.3 5869.3 6067.7 6082.6 6086.5 5237.2 5974.5 6031.2 6051.3 6060.3 5861.3 5863.6 5888.9 5941.3 5973.5 5693.9 6022.7 6033.2 6160.5 6203.3 5953.0 5997.9 6012.2 6029.8 6035.4 06:07:38 INFO - PROCESS | 1661 | 06:07:38 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:17406.52 Median:31920.54 stddev:24478.01 (76.7%) stddev-sans-first:24514.31 06:07:38 INFO - PROCESS | 1661 | Values: 643.4 687.4 724.7 747.4 747.7 14855.9 14872.0 15312.2 15317.8 15925.7 14535.1 14565.1 14567.9 14584.6 14608.4 725.5 753.6 913.2 936.1 937.0 912.4 956.0 956.0 957.9 958.2 990.3 991.0 991.3 991.7 993.7 762.3 958.4 985.6 986.1 992.5 736.7 740.6 742.8 743.2 744.1 14948.6 14990.9 15025.5 15026.5 15029.5 12077.0 12155.9 12269.4 12299.1 12373.0 7781.9 9965.8 10052.8 10177.8 10400.8 3498.4 3542.4 3547.3 3625.1 3628.1 86312.0 86993.6 88063.6 88204.6 89198.0 9277.4 9381.4 9494.8 9603.0 10054.0 21136.0 21359.7 21447.2 21462.9 21468.0 7618.9 7646.9 7697.1 7714.1 7865.7 4070.6 4118.1 4136.0 4136.5 4137.2 3556.7 3639.2 3643.7 3647.2 3648.3 629.8 644.2 644.7 653.7 661.2 524.0 534.2 534.6 551.5 581.5 7622.9 7885.7 7916.8 8022.7 8441.2 561.9 572.9 580.5 595.7 601.5 3740.6 3746.2 3761.8 3847.1 3847.4 75133.1 78835.8 84049.6 87323.6 88672.1 65318.3 72275.0 73238.3 73506.9 73728.2 65550.7 67328.7 68208.0 70150.0 72384.6 7530.3 7533.2 7543.1 7546.4 7583.4 39618.6 41148.6 42174.8 42467.0 42635.6 60123.7 61065.0 61118.7 61390.0 63197.7 9944.6 10032.2 10045.7 10070.1 10292.2 9762.3 10755.5 10918.6 11196.9 11346.0 10354.6 10392.7 10447.3 10687.4 10696.8 10058.1 10159.0 10285.1 10489.7 10507.1 9356.4 9670.0 10354.8 10554.0 10801.9 8826.6 8849.7 9017.6 9129.4 9292.6 8787.3 8814.8 8855.5 9050.9 9086.5 06:07:38 INFO - PROCESS | 1661 | -------- Summary: end -------- 06:07:38 INFO - PROCESS | 1661 | 06:07:38 INFO - TEST-INFO | 1661: exit 0 06:07:38 INFO - TEST-OK | dromaeo_css | took 1115473ms 06:07:38 INFO - TEST-START | kraken 06:07:38 INFO - operating with platform_type : mac_ 06:07:38 INFO - Initialising browser for kraken test... 06:07:38 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp3cnxLA/profile http://localhost:49281/getInfo.html) 06:07:40 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 06:07:40 INFO - PROCESS | 1671 | colorDepth:24 06:07:40 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 06:07:40 INFO - PROCESS | 1671 | __metrics 06:07:40 INFO - PROCESS | 1671 | 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" 06:07:40 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:07:41 INFO - TEST-INFO | 1671: exit 0 06:07:41 INFO - Browser initialized. 06:07:41 INFO - Running cycle 1/1 for kraken test... 06:07:41 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/T/tmp3cnxLA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:07:53 INFO - PROCESS | 1673 | RSS: Main: 237428736 06:07:53 INFO - PROCESS | 1673 | 06:07:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 06:07:57 INFO - PROCESS | 1673 | RSS: Main: 285925376 06:07:57 INFO - PROCESS | 1673 | 06:07:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 06:08:00 INFO - PROCESS | 1673 | RSS: Main: 298975232 06:08:00 INFO - PROCESS | 1673 | 06:08:03 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 06:08:04 INFO - PROCESS | 1673 | RSS: Main: 386019328 06:08:04 INFO - PROCESS | 1673 | 06:08:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 06:08:07 INFO - PROCESS | 1673 | RSS: Main: 286359552 06:08:07 INFO - PROCESS | 1673 | 06:08:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:08:09 INFO - PROCESS | 1673 | RSS: Main: 290398208 06:08:09 INFO - PROCESS | 1673 | 06:08:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 06:08:13 INFO - PROCESS | 1673 | RSS: Main: 329105408 06:08:13 INFO - PROCESS | 1673 | 06:08:16 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 06:08:16 INFO - PROCESS | 1673 | RSS: Main: 330190848 06:08:16 INFO - PROCESS | 1673 | 06:08:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 06:08:20 INFO - PROCESS | 1673 | RSS: Main: 329560064 06:08:20 INFO - PROCESS | 1673 | 06:08:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:08:22 INFO - PROCESS | 1673 | RSS: Main: 319873024 06:08:22 INFO - PROCESS | 1673 | 06:08:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:08:24 INFO - PROCESS | 1673 | RSS: Main: 326443008 06:08:24 INFO - PROCESS | 1673 | 06:08:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:08:27 INFO - PROCESS | 1673 | RSS: Main: 329367552 06:08:27 INFO - PROCESS | 1673 | 06:08:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:08:31 INFO - PROCESS | 1673 | RSS: Main: 334385152 06:08:31 INFO - PROCESS | 1673 | 06:08:33 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:08:34 INFO - PROCESS | 1673 | RSS: Main: 332144640 06:08:34 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 06:08:36 INFO - PROCESS | 1673 | RSS: Main: 396058624 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | __start_tp_report 06:08:36 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 06:08:36 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 06:08:36 INFO - PROCESS | 1673 | |i|pagename|runs| 06:08:36 INFO - PROCESS | 1673 | |0;ai-astar;114;110;113;113;113;118;116;112;112;112 06:08:36 INFO - PROCESS | 1673 | |1;audio-beat-detection;155;120;144;172;118;141;142;143;204;119 06:08:36 INFO - PROCESS | 1673 | |2;audio-dft;185;184;186;185;189;189;183;184;184;183 06:08:36 INFO - PROCESS | 1673 | |3;audio-fft;73;75;88;89;57;57;58;77;85;55 06:08:36 INFO - PROCESS | 1673 | |4;audio-oscillator;92;100;135;83;111;119;163;81;76;129 06:08:36 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;89;85;92;89;88;85;89;86;87;85 06:08:36 INFO - PROCESS | 1673 | |6;imaging-darkroom;134;130;93;95;94;94;94;95;93;94 06:08:36 INFO - PROCESS | 1673 | |7;imaging-desaturate;85;84;86;86;83;84;83;86;85;85 06:08:36 INFO - PROCESS | 1673 | |8;json-parse-financial;59;61;61;61;60;61;58;74;61;57 06:08:36 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;62;63;72;76;69;69;67;66;68;68 06:08:36 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;82;82;82;81;82;79;79;82;83;82 06:08:36 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;141;140;141;145;149;135;135;136;173;137 06:08:36 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;172;171;170;174;174;170;170;169;171;168 06:08:36 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;68;68;69;71;68;68;72;91;95;81 06:08:36 INFO - PROCESS | 1673 | __end_tp_report 06:08:36 INFO - PROCESS | 1673 | __start_cc_report 06:08:36 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1914 06:08:36 INFO - PROCESS | 1673 | __end_cc_report 06:08:36 INFO - PROCESS | 1673 | __startTimestamp1461330516629__endTimestamp 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | ------- Summary: start ------- 06:08:36 INFO - PROCESS | 1673 | Number of tests: 14 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:113.30 Median:113.00 stddev:2.26 (2.0%) stddev-sans-first:2.39 06:08:36 INFO - PROCESS | 1673 | Values: 114.0 110.0 113.0 113.0 113.0 118.0 116.0 112.0 112.0 112.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:145.80 Median:143.50 stddev:26.59 (18.5%) stddev-sans-first:27.99 06:08:36 INFO - PROCESS | 1673 | Values: 155.0 120.0 144.0 172.0 118.0 141.0 142.0 143.0 204.0 119.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:185.20 Median:185.00 stddev:2.20 (1.2%) stddev-sans-first:2.33 06:08:36 INFO - PROCESS | 1673 | Values: 185.0 184.0 186.0 185.0 189.0 189.0 183.0 184.0 184.0 183.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:71.40 Median:76.00 stddev:13.66 (18.0%) stddev-sans-first:14.48 06:08:36 INFO - PROCESS | 1673 | Values: 73.0 75.0 88.0 89.0 57.0 57.0 58.0 77.0 85.0 55.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:108.90 Median:119.50 stddev:27.92 (23.4%) stddev-sans-first:28.93 06:08:36 INFO - PROCESS | 1673 | Values: 92.0 100.0 135.0 83.0 111.0 119.0 163.0 81.0 76.0 129.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.50 stddev:2.32 (2.6%) stddev-sans-first:2.40 06:08:36 INFO - PROCESS | 1673 | Values: 89.0 85.0 92.0 89.0 88.0 85.0 89.0 86.0 87.0 85.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:94.00 stddev:16.06 (17.1%) stddev-sans-first:12.02 06:08:36 INFO - PROCESS | 1673 | Values: 134.0 130.0 93.0 95.0 94.0 94.0 94.0 95.0 93.0 94.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.16 (1.4%) stddev-sans-first:1.22 06:08:36 INFO - PROCESS | 1673 | Values: 85.0 84.0 86.0 86.0 83.0 84.0 83.0 86.0 85.0 85.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:61.30 Median:61.00 stddev:4.69 (7.7%) stddev-sans-first:4.90 06:08:36 INFO - PROCESS | 1673 | Values: 59.0 61.0 61.0 61.0 60.0 61.0 58.0 74.0 61.0 57.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.50 stddev:4.06 (5.9%) stddev-sans-first:3.67 06:08:36 INFO - PROCESS | 1673 | Values: 62.0 63.0 72.0 76.0 69.0 69.0 67.0 66.0 68.0 68.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:82.00 stddev:1.35 (1.6%) stddev-sans-first:1.41 06:08:36 INFO - PROCESS | 1673 | Values: 82.0 82.0 82.0 81.0 82.0 79.0 79.0 82.0 83.0 82.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:143.20 Median:141.00 stddev:11.40 (8.1%) stddev-sans-first:12.06 06:08:36 INFO - PROCESS | 1673 | Values: 141.0 140.0 141.0 145.0 149.0 135.0 135.0 136.0 173.0 137.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.90 Median:171.00 stddev:1.97 (1.2%) stddev-sans-first:2.05 06:08:36 INFO - PROCESS | 1673 | Values: 172.0 171.0 170.0 174.0 174.0 170.0 170.0 169.0 171.0 168.0 06:08:36 INFO - PROCESS | 1673 | 06:08:36 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.10 Median:71.50 stddev:10.27 (14.4%) stddev-sans-first:10.56 06:08:36 INFO - PROCESS | 1673 | Values: 68.0 68.0 69.0 71.0 68.0 68.0 72.0 91.0 95.0 81.0 06:08:36 INFO - PROCESS | 1673 | -------- Summary: end -------- 06:08:36 INFO - PROCESS | 1673 | 06:08:37 INFO - TEST-INFO | 1673: exit 0 06:08:37 INFO - TEST-OK | kraken | took 58813ms 06:08:37 INFO - SUITE-END | took 1174s 06:08:37 INFO - Completed test suite (00:19:34) 06:08:37 INFO - javascript benchmark 06:08:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3405.5913290405147, 3456.879009234676, 3477.200243943894, 3478.2521757822065, 3524.629913859045, 5627.2684551008415, 5686.2039314496005, 5718.342390625079, 5723.170140329655, 5730.512906402955, 5704.828855134342, 5737.540996720263, 5795.768169273224, 5833.737481813318, 5849.795257166, 5099.286099946011, 5135.5634771044415, 5158.613104017195, 5167.044096842077, 5259.947400525984, 5220.504052115043, 5271.2356708277275, 5272.657277276965, 5282.7622756976025, 5318.0, 5117.155669314578, 5195.19474481456, 5199.610029247802, 5201.349831271091, 5208.166693329087, 4317.935230971538, 5133.4609865964285, 5168.664036837594, 5172.741362931838, 5218.76515556797, 5112.667676601046, 5114.514121158484, 5125.641205115643, 5162.483751624845, 5218.530332270114, 5539.584531160139, 5582.49757521825, 5614.101743721026, 5624.128260119647, 5644.802431914904, 6148.938510614926, 6174.536909731743, 6192.659403732793, 6194.721237544321, 6234.781782637586, 6207.534434917399, 6229.221347331583, 6275.152854364648, 6276.560640755149, 6283.183186185813, 5686.061799803069, 5812.070068788974, 5857.970710146465, 5863.091220860773, 5863.853403664943, 6178.814635560941, 6244.219472565929, 6248.781292654735, 6256.624602523863, 6259.436650701458, 6124.0, 6156.907646385308, 6170.753169873155, 6174.938250617526, 6182.474489668429, 6112.746658179578, 6157.876842463125, 6200.0, 6248.50011999039, 6289.811305660837, 6147.877042459215, 6209.19280493542, 6244.781432649836, 6254.655993920378, 6266.436020758062, 6251.749930002749, 6280.7487700491465, 6289.371062893765, 6293.653849038347, 6295.244570651551, 4830.468648448667, 4929.950700492949, 4934.703917764874, 4956.058348913695, 4992.8751781205765, 4452.198604251266, 4455.977720111378, 4464.575865292792, 4497.280435130363, 4498.482674492409, 5717.285339332583, 5719.428057194247, 5720.914186287209, 5723.313202415737, 5740.052891272893, 6120.569035656853, 6130.275842464065, 6173.536984726164, 6186.938130618726, 6221.06683997395, 5807.564432667482, 5814.127880817827, 5818.854528636733, 5820.447057529613, 5827.271591051119, 5398.73006349681, 5414.40441551429, 5421.051316019681, 5426.186072089218, 5430.5927055471, 6146.7233974471255, 6150.569460137747, 6183.690815459209, 6240.875182496416, 6293.622382657055, 6177.320494745664, 6198.845028874134, 6208.503319734501, 6209.472194863487, 6221.657808820558, 6199.535034872493, 6199.690015499297, 6210.968945155245, 6215.285242197205, 6221.09794079865, 6073.240844894388, 6132.141360525334, 6139.584134579286, 6169.043798211284, 6193.81418557444, 6141.815745527641, 6151.292601350902, 6172.043333283169, 6186.721597528212, 6198.039303907902, 4817.686850354716, 5975.342712301556, 5982.0727787193055, 6022.5784195105925, 6051.364606716232, 6080.0575910733905, 6083.361247068994, 6106.582013105306, 6140.263168419639, 6151.8926593213655, 5288.968651112996, 5720.628159169641, 5960.374160713062, 6001.7599296027665, 6030.758769649165, 5966.880662386641, 6110.663913484801, 6117.724702388314, 6146.309764814533, 6151.0, 5023.572996295356, 5928.67392293428, 5947.137665038459, 6030.125631783455, 6069.514438844793], "value": 5680.516425548154, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.81210951947764, 538.0234873704227, 575.576950941058, 581.7294957844601, 616.7532986805277, 24139.982502187228, 30553.083407497754, 38393.04017399563, 39405.0, 39584.20831583367, 42624.57375426245, 43404.56595434053, 43407.56592434075, 43509.782451087776, 43716.0, 9587.089226523469, 9850.310478266525, 9854.260930430211, 9864.654737084204, 9888.307818452711, 9458.57436415363, 9611.471369074698, 9619.989901060393, 9626.903730962671, 9633.084856938614, 8667.263282620985, 9356.672516461926, 9540.761480962961, 9641.807163856718, 9644.0, 9530.904690953072, 9599.32804703671, 9602.66390676323, 9602.6639067633, 9603.711888643387, 8947.865782013276, 9653.662121825702, 9662.75843103921, 9681.983391743906, 9691.273154513368, 40497.79751101255, 40952.180956380704, 41039.0, 41070.79464602658, 41079.1784164315, 3227.580414546094, 3312.8840490582957, 3316.6849149330774, 3460.6471717868503, 3849.268638958589, 29051.709482905113, 29422.558661620093, 30434.93477728291, 30460.695393046008, 30487.69512304893, 2806.6211061506638, 2808.859557022161, 2826.364068084685, 2832.235296469962, 2911.9854400728073, 447287.0, 447573.0, 448311.0, 448768.75615621713, 450180.7382520126, 59842.40157598456, 60440.0, 60459.0, 60536.0, 60555.0, 63599.0, 63668.363316366245, 63705.36294636994, 63708.0, 63897.36102639007, 14868.479603213835, 16733.0, 16755.32978680839, 16776.832231677527, 16781.160941952854, 11495.482703278372, 11607.825882611767, 11616.954474097203, 11619.941900290445, 12707.68230794219, 14576.708465830625, 15739.842601574066, 15903.0, 15941.36234550605, 15948.963317384332, 4614.618751526356, 7590.696372145163, 7900.525968441912, 8074.475159114639, 8096.271335579826, 960.2030314838668, 978.2467500025083, 1020.3520764314713, 1082.9729256768487, 1142.2004596782285, 29945.520190306484, 30041.0, 30051.849740751593, 30341.241468963453, 30474.69525304763, 1111.1555218034352, 1129.6839182352467, 1168.1355796710372, 1191.7497325561555, 1234.7777400067894, 14910.254487275593, 15510.457134000257, 15687.529374118794, 15899.889584100212, 16192.595185120466, 642014.0, 643911.0, 648739.0, 649137.0, 650298.0, 61104.0, 62030.68984655048, 62071.6896415515, 62103.0, 62205.06692399618, 1250.6623211732963, 1324.317976242236, 1372.0190064104013, 1505.0292561297902, 1518.764591488321, 26460.0, 28903.421931561672, 28937.0, 29507.262318441353, 29713.108606741833, 45822.77088614669, 46184.0, 46713.0, 46891.0, 46923.59229223129, 359303.0, 359609.0, 360255.0, 360564.0, 364704.0, 33396.49905251375, 38912.80543597377, 40083.39874901937, 40808.38787418132, 40842.79578602088, 42190.789046054575, 43057.124858762865, 43448.56551434445, 43765.781171095216, 44192.55807441884, 36300.81849590735, 38317.80841095777, 39054.414183787834, 39566.604333957446, 39957.80021099876, 35511.46732798959, 35542.64457355497, 35909.64090359167, 36190.63809361873, 36372.63627363693, 35076.416833509364, 35315.47026794651, 35829.46255806113, 35924.64075359317, 36749.0, 30827.218012037316, 31956.20109497281, 32032.51951220687, 33320.66679333176, 33458.66541334555, 20103.899480502503, 24389.878050609634, 24612.956711081097, 24892.37769055788, 25224.74775252224], "value": 19551.43347110949, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.1723721566103, 393.4884649955056, 395.95446523649787, 396.77185618269493, 408.3629537920844, 14878.55364339069, 14954.252287385641, 16441.26014329355, 19047.52381190469, 20026.99999999998, 19452.51368715783, 19669.901650491764, 20340.796592034076, 21331.040103195355, 21391.465213369673, 5667.518260947814, 5742.569307301948, 5830.242068531085, 5835.328937172237, 5877.588568800185, 4049.8177582008734, 5620.57845661577, 5796.913046304308, 5829.5919285650025, 5844.064949608063, 6013.1581578579035, 6331.5884467509695, 6358.395952384516, 6362.586431881936, 6368.235811702579, 6267.592606480587, 6347.714352854109, 6353.618782873019, 6354.809355719336, 6364.236291645032, 5361.0, 5523.585731070146, 5542.0855558832745, 5542.224088627595, 5544.168374743821, 24588.0, 25578.48843023148, 25753.227403177934, 25753.742462575323, 26098.478030439474, 2111.2716112941166, 2174.7716489768663, 2359.693239878805, 2430.4045508850268, 2525.9621105683427, 9982.001799820033, 12272.263664180178, 13616.93191534036, 14134.0, 15138.318775655121, 1681.512361415188, 1707.4206359117766, 1733.7399390091587, 1738.2351765223261, 1742.3988723890363, 84374.0, 113856.43071784671, 114800.0, 114885.0, 115452.84547154505, 9579.425234485952, 11469.885301146882, 11581.478833452515, 11652.53389864413, 11666.008389287006, 14980.250987450583, 15084.321205545777, 15092.924535377473, 15111.4710985115, 15139.015963962307, 8377.455465394853, 8543.871841922377, 8565.357598180162, 8676.91323086761, 8766.649334026695, 6902.240753517109, 8424.368172386972, 8477.86319061951, 8545.957270213734, 8614.483131012033, 4499.482559505634, 4596.7931443085145, 4603.915376697621, 4651.534846515389, 4663.323818046432, 1882.802305757903, 1944.8346890514465, 1950.3173889138689, 1951.5511432370633, 1957.3051566693903, 884.540039179623, 959.0936564946059, 973.1582181413027, 976.2239019979133, 1042.1276109124983, 19313.90343048276, 20051.899740501205, 20063.59872802565, 20239.696404553943, 20374.388768336972, 943.0899182288987, 956.7082039977873, 964.2952807071449, 994.4878387630376, 1060.6075751971666, 10284.382937023802, 10528.210384221215, 10529.473526323653, 10537.209709271836, 10540.420276884844, 557008.0, 604065.0, 608958.0, 618346.0, 618535.0, 130435.34782326217, 135830.3208483971, 139945.0, 142511.0, 143631.0, 883.9248663863515, 999.6900960702205, 1043.6816770884952, 1044.0916402729522, 1054.7626783973699, 17779.73330400019, 18011.2795488179, 18045.819541804412, 18089.909550452165, 18266.634667306844, 42337.78831105825, 51238.0, 51618.74190629173, 51634.74182629063, 52590.73704631453, 72767.27232727605, 75150.0, 76666.23333766813, 77185.22814772004, 79493.80759288731, 7589.1651918290045, 7883.172266911892, 7969.362451004022, 8014.517314296873, 8335.874961875455, 8999.145081217273, 11650.0, 12166.330851802883, 12377.752444951231, 12576.0, 10370.377777333384, 10683.358998460117, 10755.677329680122, 10817.51321190564, 11430.371329576954, 10814.89185108139, 10845.674629761119, 11005.1746119043, 11160.49777760019, 11410.600628977945, 10914.945425272823, 11173.0, 11485.540578376771, 11611.76776464449, 11642.70893227676, 8072.192780721881, 8128.918710812816, 8293.792655183668, 8295.04606970182, 8426.747197584082, 10009.749756256151, 11075.0, 11372.374519401512, 11389.601363952223, 11417.828732568853], "value": 9551.520334597864, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.502474134054, 1614.8223695393506, 1617.5794293483693, 1883.3973128598855, 1995.2517805822827, 7823.687052517899, 8518.97772267328, 8756.824863502727, 8764.824703505941, 8767.561621918903, 8349.165083491647, 8585.356098292637, 8589.699360522383, 8590.140985901407, 8591.097934716843, 1957.696557033655, 2247.43814046488, 2282.2696737044153, 2283.5432913417385, 2291.32405940248, 2368.538135063663, 2403.639454081884, 2409.915652952147, 2416.4200591858003, 2422.757724227572, 2379.1435083369975, 2401.0875867170453, 2405.3265085775924, 2411.1560953666253, 2415.5531226723024, 2371.087131454385, 2387.4270175157926, 2393.198278576679, 2396.376941995085, 2403.7596240375997, 2338.3335749311364, 2366.810655147601, 2370.5377451396985, 2371.5968285391523, 2372.41875740443, 8482.36382271326, 8514.957425212897, 8668.653253869837, 8675.045744968049, 8688.78278043054, 5156.1750119980625, 5344.5724342052545, 5348.090824559834, 5356.839294821161, 5359.598030147716, 3432.2105915639295, 3462.9653703462895, 3522.4892390603477, 3552.0231936217747, 3567.8751243706347, 1029.233221250166, 1043.1394099867564, 1043.519980808829, 1051.7265510967127, 1052.9105026072793, 11353.318800871892, 11610.013148882355, 11611.419429028514, 11620.418979051097, 11621.128415368881, 4365.978170109129, 4368.7815609219415, 4605.861824145281, 4609.985803123308, 4615.676902616851, 9048.954755226314, 9053.90946090544, 9059.547022648841, 9063.54682265884, 9092.81814363709, 4457.598816106566, 5135.97432012845, 5155.072087024297, 5157.974210128926, 5165.896682066413, 4299.591538803808, 4490.034642551802, 4665.580097791215, 4854.781534830941, 4859.125357435625, 3773.6981041516615, 3775.905602359963, 3853.1523064925673, 3873.2447172801035, 3886.9222615547533, 1205.8673545909942, 1216.6714986953473, 1217.2818037358002, 1226.1539537718945, 1229.342301868514, 651.3063587279532, 771.4715419937248, 918.5912269040213, 925.1396201532505, 929.4051806843626, 4846.5638092571835, 5546.278983732089, 5568.582356323292, 5702.800401985911, 5815.244018277597, 987.5457289646699, 1038.4703801061512, 1069.3370110531364, 1072.088724584113, 1072.3083611070815, 2902.8693708783157, 3870.2066076454257, 3894.5131858517684, 3922.5292964844402, 3929.155231625214, 10267.537960791633, 10542.314749541256, 10583.100436462986, 10593.364398136137, 10751.193660475496, 8674.522901240367, 8818.955905220433, 10448.582056717647, 10481.423521706227, 10500.422476763852, 7680.807979800438, 7872.212778722082, 7984.960075199587, 8041.959790201012, 8129.75610731679, 5061.215511595708, 5849.151872978479, 5861.531077513703, 5931.991561434653, 5990.880182396415, 9727.562259698472, 9826.361286516354, 9837.114659680663, 9852.310338276251, 10012.799744004933, 9697.418154910445, 9912.107910288109, 9929.702108936743, 9975.551100200653, 10002.8499572505, 7362.726508104781, 7372.778816635509, 7415.8887616684715, 7499.437542184467, 7524.473286870086, 8041.954545908994, 8175.509469431853, 8177.223163799429, 8182.549959752033, 8193.50838949665, 6084.330723620486, 7210.783676489714, 7249.238829922782, 7466.0, 7508.962455187689, 6449.258335291502, 6477.190351206099, 6906.205786334435, 7139.143302803489, 7308.940203670331, 5295.258663786996, 6803.353681400259, 6807.5575087619145, 6808.625525595941, 6811.9659401702675, 4614.953850461587, 4687.9296810547185, 4703.365045718784, 4745.525447455227, 4826.300186473012, 4832.879178020437, 4880.389951256093, 4928.704277743347, 4950.801967921387, 4994.300797888372], "value": 4481.7377925712635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.12389998254923, 318.20448877805484, 325.22271770468586, 328.3285680782799, 330.3838341493116, 5500.0, 5683.3464151622575, 5719.828405147847, 5767.30792304924, 5795.130730390439, 5296.285001524793, 5346.518813306801, 5502.9174562381495, 5623.297087864017, 5922.674252916088, 4321.373400856874, 4326.6538676905775, 4331.1554246921705, 4332.393464914914, 4340.437442520698, 4155.459790227266, 4326.935095973563, 4334.154839806239, 4348.9782551087355, 4384.49578298495, 4289.227938970999, 4303.332983387564, 4305.526392096866, 4309.202797482476, 4329.112531930962, 3704.481372607837, 4222.880936551816, 4233.851815186485, 4328.84849030284, 4345.891352716191, 3182.3953448844645, 3264.722498587623, 3772.2270532849516, 3774.641409066134, 3779.130799916035, 4787.377640906695, 5134.024535338273, 5495.313085864267, 5508.3114610673665, 5567.610267281292, 4885.60915126789, 4904.656674032819, 4916.852494425138, 4923.046172296629, 4947.678400903929, 5828.513728999078, 5986.371430999726, 6032.819015429544, 6048.667323297217, 6090.558834258674, 3455.706264967481, 4069.5930406959365, 4079.2249472600406, 4081.1837632473625, 4111.568285330027, 5520.065929126165, 6073.1497590337385, 6261.87476250477, 6365.872682546369, 6393.456556192729, 3840.2895464339185, 4123.567025462343, 4128.752274863517, 4130.6661736118995, 4145.150244199932, 3692.5015122958057, 4159.0, 4180.853670121531, 4208.431861698693, 4211.241976444208, 4086.6730661547003, 4902.2646603009125, 4905.950940490621, 4920.827771027997, 4968.478309777422, 5063.240513922868, 5774.04728219839, 5846.29844418664, 6081.878362432726, 6108.664023478751, 1233.0695216779443, 1327.3097989045643, 1440.9351579178963, 1448.3120517753982, 1449.145004447381, 793.3890904003885, 825.8141918068507, 923.882102655794, 961.6489981156827, 968.080323692495, 4670.929936050962, 5356.5982551308025, 5423.945760542423, 5605.271314729059, 5669.886602267931, 6102.805652262957, 6171.444569988723, 6253.312135665074, 6290.0, 6301.653409062454, 4174.415581818548, 4702.811887524529, 5609.214709940612, 5624.690642014729, 5689.6586204827845, 2940.9559606339867, 3269.476883698597, 3297.5383446317533, 3320.5185248138882, 3322.534845121685, 5283.339582552181, 6164.722587483574, 6524.184476940382, 6589.769358072444, 6624.238212605515, 5709.401367617073, 5889.499392551681, 6315.936840631535, 6372.2671892732005, 6439.908843755024, 5334.093204155303, 5340.786368545215, 5564.276644036249, 6270.7491700331475, 6480.269403335591, 5152.562032227303, 5203.583713302851, 5980.5514586405325, 6355.205599300088, 6368.363163683594, 6251.156093927261, 6263.6241825490615, 6275.341089185752, 6275.466585340115, 6341.936580634135, 5730.426957304403, 5761.366249712444, 6231.53263505248, 6582.597351966593, 6588.209414870246, 3378.831058447117, 3876.748011379364, 3998.9600103998587, 4109.0, 4138.8344466221015, 5718.370979192368, 6208.410201030895, 6220.875582488235, 6266.968665156827, 6274.121622972696, 5752.28096487939, 5869.266341707286, 6067.696615169312, 6082.574219804571, 6086.482648974887, 5237.16205407133, 5974.492168165754, 6031.155638210742, 6051.33435322123, 6060.303065147565, 5861.296644402699, 5863.560232982458, 5888.852778680567, 5941.316748573797, 5973.492253158356, 5693.946619875336, 6022.66875321844, 6033.1644127260115, 6160.537959653134, 6203.348648391854, 5952.970235148797, 5997.940020599738, 6012.218411606463, 6029.78895738647, 6035.426634469718], "value": 4385.408337180244, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.4016364780754, 687.3504538211389, 724.7101159536185, 747.3722073458293, 747.700919632147, 14855.851441485582, 14872.0, 15312.157831319273, 15317.770233446507, 15925.681486370295, 14535.055221410626, 14565.053271537368, 14567.927160364183, 14584.562463126096, 14608.415663373478, 725.5211560370157, 753.6043577122, 913.1690161952624, 936.0966667166183, 936.9786932243853, 912.3795818843184, 955.961761529538, 956.0248546482584, 957.9185769209626, 958.1568219966455, 990.3117333453253, 991.0, 991.3110388280148, 991.7024892532247, 993.7416271769356, 762.3177256355586, 958.4153666263643, 985.6057576969198, 986.0878687214334, 992.483908367652, 736.7126820539994, 740.6185814305637, 742.7734540965004, 743.2418932688628, 744.0922075068407, 14948.626284342978, 14990.850091499055, 15025.549233523012, 15026.474073407488, 15029.473968411163, 12077.0, 12155.878441215564, 12269.447874845653, 12299.077569182258, 12373.010159187243, 7781.8832717509285, 9965.75085622858, 10052.849207261897, 10177.796444071077, 10400.843987340197, 3498.405271103893, 3542.3800834854055, 3547.343741407848, 3625.111847597329, 3628.0567052566257, 86312.0, 86993.56503217507, 88063.55968220181, 88204.55897720471, 89198.0, 9277.443353398818, 9381.437113773196, 9494.762630934281, 9602.95198524003, 10054.0, 21136.0, 21359.67960480594, 21447.249346273118, 21462.892685536473, 21468.0, 7618.885716714254, 7646.923530764621, 7697.076350837935, 7714.074311082706, 7865.685372585032, 4070.633642972147, 4118.052847844952, 4136.048708796993, 4136.462259906193, 4137.172565486914, 3556.6976806971697, 3639.217568222845, 3643.690286325639, 3647.2340808430527, 3648.325059863934, 629.8488362792897, 644.1947565543071, 644.7195469970578, 653.6764301670703, 661.2329697550817, 524.048851334826, 534.2253732088487, 534.5940177333665, 551.4926267833603, 581.5289615411446, 7622.885656715153, 7885.684572617031, 7916.802079948047, 8022.719204827919, 8441.155884411632, 561.9213310136585, 572.9398413166641, 580.5299932254994, 595.7200115945499, 601.4767152577199, 3740.551133863954, 3746.1571146492365, 3761.8119094045187, 3847.115163512408, 3847.3844184930836, 75133.12167195864, 78835.81746273811, 84049.57975210085, 87323.56338218268, 88672.11327886768, 65318.346816532176, 72275.0, 73238.2676173242, 73506.89739653909, 73728.15679608053, 65550.67224663941, 67328.6633566829, 68208.0, 70150.0, 72384.63807680928, 7530.322270995636, 7533.2466753324225, 7543.057117860268, 7546.358559522282, 7583.393328533814, 39618.60381396149, 41148.588514114475, 42174.7891260554, 42467.0, 42635.57364426316, 60123.69938150281, 61065.0, 61118.694406527684, 61390.0, 63197.68401157965, 9944.552495137592, 10032.247581431568, 10045.69862904114, 10070.14403775658, 10292.176625870063, 9762.316637835283, 10755.516001780095, 10918.617848375268, 11196.94401527987, 11346.0, 10354.637587684394, 10392.740181495523, 10447.320924139907, 10687.358758474516, 10696.786064278827, 10058.094771470604, 10159.034891685573, 10285.074343309137, 10489.737756556147, 10507.106895913934, 9356.4386136832, 9669.984651611761, 10354.792904141725, 10554.0, 10801.891981080089, 8826.646934122564, 8849.69026084084, 9017.639294428149, 9129.360944733804, 9292.58183381763, 8787.34094942869, 8814.823703526023, 8855.512946787989, 9050.909490905007, 9086.500242486727], "value": 6699.238868701688, "unit": "score"}], "name": "dromaeo_css", "value": 7203.230879054632, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 113.0, 113.0, 113.0, 118.0, 116.0, 112.0, 112.0, 112.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 120.0, 144.0, 172.0, 118.0, 141.0, 142.0, 143.0, 204.0, 119.0], "value": 145.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 186.0, 185.0, 189.0, 189.0, 183.0, 184.0, 184.0, 183.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 88.0, 89.0, 57.0, 57.0, 58.0, 77.0, 85.0, 55.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 100.0, 135.0, 83.0, 111.0, 119.0, 163.0, 81.0, 76.0, 129.0], "value": 108.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 92.0, 89.0, 88.0, 85.0, 89.0, 86.0, 87.0, 85.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 130.0, 93.0, 95.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 86.0, 83.0, 84.0, 83.0, 86.0, 85.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 61.0, 60.0, 61.0, 58.0, 74.0, 61.0, 57.0], "value": 61.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 72.0, 76.0, 69.0, 69.0, 67.0, 66.0, 68.0, 68.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 81.0, 82.0, 79.0, 79.0, 82.0, 83.0, 82.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 141.0, 145.0, 149.0, 135.0, 135.0, 136.0, 173.0, 137.0], "value": 143.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 170.0, 174.0, 174.0, 170.0, 170.0, 169.0, 171.0, 168.0], "value": 170.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 71.0, 68.0, 68.0, 72.0, 91.0, 95.0, 81.0], "value": 75.1, "unit": "score"}], "name": "kraken", "value": 1498.3000000000002, "alertThreshold": 2.0}]} 06:08:37 INFO - Return code: 0 06:08:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:08:37 ERROR - # TBPL SUCCESS # 06:08:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:37 INFO - 2016-04-22 06:08:37.898 screenresolution[1677:9703] starting screenresolution argv=screenresolution get 06:08:37 INFO - 2016-04-22 06:08:37.900 screenresolution[1677:9703] Display 0: 1600x1200x32@60 06:08:37 INFO - 2016-04-22 06:08:37.906 screenresolution[1678:9706] starting screenresolution argv=screenresolution list 06:08:37 INFO - Available Modes on Display 0 06:08:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:38 INFO - Intel Iris: 06:08:38 INFO - Chipset Model: Intel Iris 06:08:38 INFO - Type: GPU 06:08:38 INFO - Bus: Built-In 06:08:38 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:38 INFO - Vendor: Intel (0x8086) 06:08:38 INFO - Device ID: 0x0a2e 06:08:38 INFO - Revision ID: 0x0009 06:08:38 INFO - Displays: 06:08:38 INFO - Display: 06:08:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:38 INFO - Display Serial Number: 1600x1200 60 06:08:38 INFO - Main Display: Yes 06:08:38 INFO - Mirror: Off 06:08:38 INFO - Online: Yes 06:08:38 INFO - Rotation: Supported 06:08:38 INFO - Return code: 0 06:08:38 INFO - Running post-action listener: _resource_record_post_action 06:08:38 INFO - Running post-run listener: _resource_record_post_run 06:08:39 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 37701120; Write bytes: 693379584; Read time: 395; Write time: 1917 06:08:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375667200; Write bytes: 369669632; Read time: 27869; Write time: 740 06:08:39 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 36734464; Write bytes: 296418304; Read time: 379; Write time: 1124 06:08:39 INFO - Running post-run listener: _upload_blobber_files 06:08:39 INFO - Blob upload gear active. 06:08:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:08:39 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 06:08:39 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:08:39 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:08:39 INFO - (blobuploader) - INFO - Open directory for files ... 06:08:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:08:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:08:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:40 INFO - (blobuploader) - INFO - Done attempting. 06:08:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:08:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:08:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:41 INFO - (blobuploader) - INFO - Done attempting. 06:08:41 INFO - (blobuploader) - INFO - Iteration through files over. 06:08:41 INFO - Return code: 0 06:08:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:08:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:08:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc7bc2673c34b4a6790b0a69e340f67b05d2eeea938ce11ee00d659f675c9cd5fa0f5d6437569e415600687b46d9592944f7e377d9691ff62e5d0866e4ee172d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ffdb865d5236bac158d1bfb6d877ffc1d79679d3748767c99662c031b4b83fb5a95e7b863891abdc95895efbccb9ba69b87b9b36c9e6fdc59616691b07eecd37"} 06:08:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:08:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:08:41 INFO - Contents: 06:08:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc7bc2673c34b4a6790b0a69e340f67b05d2eeea938ce11ee00d659f675c9cd5fa0f5d6437569e415600687b46d9592944f7e377d9691ff62e5d0866e4ee172d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ffdb865d5236bac158d1bfb6d877ffc1d79679d3748767c99662c031b4b83fb5a95e7b863891abdc95895efbccb9ba69b87b9b36c9e6fdc59616691b07eecd37"} 06:08:41 INFO - Running post-run listener: copy_logs_to_upload_dir 06:08:41 INFO - Copying logs to upload dir... 06:08:41 INFO - mkdir: /builds/slave/test/build/upload/logs 06:08:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.175443 ========= master_lag: 1.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-22 06:08:42.541279) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 06:08:42.544670) ========= 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.g47mmQK83Y/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.hXQGHeGMUX/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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/Fx-Team/sha512/fc7bc2673c34b4a6790b0a69e340f67b05d2eeea938ce11ee00d659f675c9cd5fa0f5d6437569e415600687b46d9592944f7e377d9691ff62e5d0866e4ee172d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ffdb865d5236bac158d1bfb6d877ffc1d79679d3748767c99662c031b4b83fb5a95e7b863891abdc95895efbccb9ba69b87b9b36c9e6fdc59616691b07eecd37"} build_url:https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011138 build_url: 'https://queue.taskcluster.net/v1/task/I4vTWX0DROiS0WyhGRP33Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc7bc2673c34b4a6790b0a69e340f67b05d2eeea938ce11ee00d659f675c9cd5fa0f5d6437569e415600687b46d9592944f7e377d9691ff62e5d0866e4ee172d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ffdb865d5236bac158d1bfb6d877ffc1d79679d3748767c99662c031b4b83fb5a95e7b863891abdc95895efbccb9ba69b87b9b36c9e6fdc59616691b07eecd37"}' ========= master_lag: 0.18 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 06:08:42.739778) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-22 06:08:42.740128) ========= 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.g47mmQK83Y/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.hXQGHeGMUX/Listeners TMPDIR=/var/folders/72/tvk_0qk91l1gsxjmnd64n0bm00000w/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.005144 ========= master_lag: 3.17 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-22 06:08:45.915156) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 06:08:45.915477) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 06:08:46.069702) ========= ========= Total master_lag: 5.56 =========