builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0237 starttime: 1468324108.29 results: success (0) buildid: 20160712030118 builduid: e637bef27eef423097e8e5a885d7d493 revision: 6bd7cb39abaf55225b932acce3a2d8c6320c2273 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:28.291134) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:28.291595) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:28.291908) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:28.316451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:28.316890) ========= 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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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-07-12 04:48:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.8M=0s 2016-07-12 04:48:28 (28.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.216041 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:28.555666) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:28.556132) ========= 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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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.025746 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:28.604466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:28.604806) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6bd7cb39abaf55225b932acce3a2d8c6320c2273 --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 6bd7cb39abaf55225b932acce3a2d8c6320c2273 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eKcD9KQlqe/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.I4rd5L0gs4/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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-07-12 04:48:28,687 truncating revision to first 12 chars 2016-07-12 04:48:28,687 Setting DEBUG logging. 2016-07-12 04:48:28,687 attempt 1/10 2016-07-12 04:48:28,687 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6bd7cb39abaf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 04:48:29,374 unpacking tar archive at: fx-team-6bd7cb39abaf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.966697 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:29.593061) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:29.593391) ========= script_repo_revision: 6bd7cb39abaf55225b932acce3a2d8c6320c2273 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:29.593813) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:29.594090) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 04:48:29.611859) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-12 04:48:29.612181) ========= /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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:48:29 INFO - MultiFileLogger online at 20160712 04:48:29 in /builds/slave/test 04:48:29 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 04:48:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:48:29 INFO - {'append_to_log': False, 04:48:29 INFO - 'base_work_dir': '/builds/slave/test', 04:48:29 INFO - 'blob_upload_branch': 'Fx-Team', 04:48:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:48:29 INFO - 'branch': 'Fx-Team', 04:48:29 INFO - 'buildbot_json_path': 'buildprops.json', 04:48:29 INFO - 'config_files': ('talos/mac_config.py',), 04:48:29 INFO - 'default_actions': ('clobber', 04:48:29 INFO - 'read-buildbot-config', 04:48:29 INFO - 'download-and-extract', 04:48:29 INFO - 'populate-webroot', 04:48:29 INFO - 'create-virtualenv', 04:48:29 INFO - 'install', 04:48:29 INFO - 'run-tests'), 04:48:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:48:29 INFO - 'download_minidump_stackwalk': True, 04:48:29 INFO - 'download_symbols': 'ondemand', 04:48:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:48:29 INFO - 'tooltool.py': '/tools/tooltool.py', 04:48:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:48:29 INFO - '/tools/misc-python/virtualenv.py')}, 04:48:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:48:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:48:29 INFO - 'installer_path': 'installer.exe', 04:48:29 INFO - 'log_level': 'info', 04:48:29 INFO - 'log_name': 'talos', 04:48:29 INFO - 'log_to_console': True, 04:48:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:48:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:48:29 INFO - 'opt_config_files': (), 04:48:29 INFO - 'pip_index': False, 04:48:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:48:29 INFO - 'cmd': ('bash', 04:48:29 INFO - '-c', 04:48:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:48:29 INFO - 'enabled': True, 04:48:29 INFO - 'halt_on_failure': False, 04:48:29 INFO - 'name': 'check_screen_resolution'},), 04:48:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:48:29 INFO - 'cmd': ('bash', 04:48:29 INFO - '-c', 04:48:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:48:29 INFO - 'enabled': True, 04:48:29 INFO - 'halt_on_failure': False, 04:48:29 INFO - 'name': 'check_screen_resolution'},), 04:48:29 INFO - 'run_cmd_checks_enabled': True, 04:48:29 INFO - 'sps_profile': False, 04:48:29 INFO - 'sps_profile_interval': 0, 04:48:29 INFO - 'suite': 'dromaeojs', 04:48:29 INFO - 'system_bits': '32', 04:48:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:48:29 INFO - 'title': 't-yosemite-r7-0237', 04:48:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:48:29 INFO - 'use_talos_json': True, 04:48:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:48:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:48:29 INFO - 'work_dir': 'build'} 04:48:29 INFO - ##### 04:48:29 INFO - ##### Running clobber step. 04:48:29 INFO - ##### 04:48:29 INFO - Running pre-action listener: _resource_record_pre_action 04:48:29 INFO - Running main action method: clobber 04:48:29 INFO - rmtree: /builds/slave/test/build 04:48:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:48:31 INFO - Running post-action listener: _resource_record_post_action 04:48:31 INFO - ##### 04:48:31 INFO - ##### Finished clobber step (success) 04:48:31 INFO - ##### 04:48:31 INFO - ##### 04:48:31 INFO - ##### Running read-buildbot-config step. 04:48:31 INFO - ##### 04:48:31 INFO - Running pre-action listener: _resource_record_pre_action 04:48:31 INFO - Running main action method: read_buildbot_config 04:48:31 INFO - Using buildbot properties: 04:48:31 INFO - { 04:48:31 INFO - "project": "", 04:48:31 INFO - "product": "firefox", 04:48:31 INFO - "script_repo_revision": "production", 04:48:31 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:48:31 INFO - "repository": "", 04:48:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:48:31 INFO - "buildid": "20160712030118", 04:48:31 INFO - "pgo_build": "False", 04:48:31 INFO - "basedir": "/builds/slave/test", 04:48:31 INFO - "buildnumber": 79, 04:48:31 INFO - "slavename": "t-yosemite-r7-0237", 04:48:31 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:48:31 INFO - "platform": "yosemite_r7", 04:48:31 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:48:31 INFO - "branch": "fx-team", 04:48:31 INFO - "repo_path": "integration/fx-team", 04:48:31 INFO - "revision": "6bd7cb39abaf55225b932acce3a2d8c6320c2273", 04:48:31 INFO - "stage_platform": "macosx64", 04:48:31 INFO - "builduid": "e637bef27eef423097e8e5a885d7d493", 04:48:31 INFO - "slavebuilddir": "test" 04:48:31 INFO - } 04:48:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:48:31 INFO - Running post-action listener: _resource_record_post_action 04:48:31 INFO - ##### 04:48:31 INFO - ##### Finished read-buildbot-config step (success) 04:48:31 INFO - ##### 04:48:31 INFO - ##### 04:48:31 INFO - ##### Running download-and-extract step. 04:48:31 INFO - ##### 04:48:31 INFO - Running pre-action listener: _resource_record_pre_action 04:48:31 INFO - Running main action method: download_and_extract 04:48:31 INFO - mkdir: /builds/slave/test/build/tests 04:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:31 INFO - https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:48:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:48:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:48:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:48:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 04:48:32 INFO - Downloaded 1199 bytes. 04:48:32 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:48:32 INFO - Using the following test package requirements: 04:48:32 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:48:32 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:48:32 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:48:32 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:48:32 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:48:32 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:48:32 INFO - u'jsshell-mac.zip'], 04:48:32 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:48:32 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:48:32 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:48:32 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:48:32 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:48:32 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:48:32 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:48:32 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:48:32 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:48:32 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:48:32 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:48:32 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:32 INFO - https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:48:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:48:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:48:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:48:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:48:34 INFO - Downloaded 18772437 bytes. 04:48:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:48:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:48:37 INFO - Return code: 0 04:48:37 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:48: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')]} 04:48:37 INFO - https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:48:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:48:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:48:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:48:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:48:39 INFO - Downloaded 18772437 bytes. 04:48:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:48:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:48:42 INFO - Return code: 0 04:48: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')]} 04:48:42 INFO - https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:48:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:48:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:48:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:48:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:48:44 INFO - Downloaded 14403317 bytes. 04:48:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:48:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:48:44 INFO - Return code: 0 04:48:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:44 INFO - https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:48:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:48:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:48:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 04:48:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 04:48:47 INFO - Downloaded 112428811 bytes. 04:48:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:48:47 INFO - mkdir: /builds/slave/test/properties 04:48:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:48:47 INFO - Writing to file /builds/slave/test/properties/build_url 04:48:47 INFO - Contents: 04:48:47 INFO - build_url:https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:48:48 INFO - Running post-action listener: _resource_record_post_action 04:48:48 INFO - Running post-action listener: set_extra_try_arguments 04:48:48 INFO - ##### 04:48:48 INFO - ##### Finished download-and-extract step (success) 04:48:48 INFO - ##### 04:48:48 INFO - ##### 04:48:48 INFO - ##### Running populate-webroot step. 04:48:48 INFO - ##### 04:48:48 INFO - Running pre-action listener: _resource_record_pre_action 04:48:48 INFO - Running main action method: populate_webroot 04:48:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:48:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:48:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:48:48 INFO - '--tppagecycles', 04:48:48 INFO - '7'], 04:48:48 INFO - 'tests': ['tsvgm']}}, 04:48:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:48:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:48:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:48:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:48:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:48 INFO - 'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:48 INFO - 'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['damp', 'tps']}, 04:48:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:48 INFO - 'tests': ['damp', 'tps']}, 04:48:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['dromaeo_dom']}, 04:48:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:48:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['basic_compositor_video']}, 04:48:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:48:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['a11yr', 04:48:48 INFO - 'ts_paint', 04:48:48 INFO - 'tpaint', 04:48:48 INFO - 'sessionrestore', 04:48:48 INFO - 'sessionrestore_no_auto_restore', 04:48:48 INFO - 'tabpaint']}, 04:48:48 INFO - 'other-e10s': {'tests': ['a11yr', 04:48:48 INFO - 'ts_paint', 04:48:48 INFO - 'tpaint', 04:48:48 INFO - 'sessionrestore', 04:48:48 INFO - 'sessionrestore_no_auto_restore', 04:48:48 INFO - 'tabpaint']}, 04:48:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:48:48 INFO - 'ts_paint', 04:48:48 INFO - 'tpaint', 04:48:48 INFO - 'sessionrestore', 04:48:48 INFO - 'sessionrestore_no_auto_restore', 04:48:48 INFO - 'tabpaint']}, 04:48:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:48:48 INFO - 'ts_paint', 04:48:48 INFO - 'tpaint', 04:48:48 INFO - 'sessionrestore', 04:48:48 INFO - 'sessionrestore_no_auto_restore', 04:48:48 INFO - 'tabpaint']}, 04:48:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['a11yr', 04:48:48 INFO - 'ts_paint', 04:48:48 INFO - 'tpaint', 04:48:48 INFO - 'sessionrestore', 04:48:48 INFO - 'sessionrestore_no_auto_restore', 04:48:48 INFO - 'tabpaint']}, 04:48:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['a11yr', 04:48:48 INFO - 'ts_paint', 04:48:48 INFO - 'tpaint', 04:48:48 INFO - 'sessionrestore', 04:48:48 INFO - 'sessionrestore_no_auto_restore', 04:48:48 INFO - 'tabpaint']}, 04:48:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['tsvgx', 04:48:48 INFO - 'tsvgr_opacity', 04:48:48 INFO - 'tart', 04:48:48 INFO - 'tscrollx', 04:48:48 INFO - 'cart']}, 04:48:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:48:48 INFO - 'tsvgr_opacity', 04:48:48 INFO - 'tart', 04:48:48 INFO - 'tscrollx', 04:48:48 INFO - 'cart']}, 04:48:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:48 INFO - 'talos_options': ['--disable-e10s'], 04:48:48 INFO - 'tests': ['tp5o']}, 04:48:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:48 INFO - 'tests': ['tp5o']}, 04:48:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:48 INFO - 'talos_options': ['--disable-e10s', 04:48:48 INFO - '--xperf_path', 04:48:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:48 INFO - 'tests': ['tp5n']}, 04:48:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:48 INFO - 'talos_options': ['--xperf_path', 04:48:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:48 INFO - 'tests': ['tp5n']}}, 04:48:48 INFO - 'talos.zip': {'path': '', 04:48:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:48:48 INFO - Running post-action listener: _resource_record_post_action 04:48:48 INFO - ##### 04:48:48 INFO - ##### Finished populate-webroot step (success) 04:48:48 INFO - ##### 04:48:48 INFO - ##### 04:48:48 INFO - ##### Running create-virtualenv step. 04:48:48 INFO - ##### 04:48:48 INFO - Running pre-action listener: _resource_record_pre_action 04:48:48 INFO - Running main action method: create_virtualenv 04:48:48 INFO - Creating virtualenv /builds/slave/test/build/venv 04:48:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:48:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:48:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:48:48 INFO - Using real prefix '/tools/python27' 04:48:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:48:49 INFO - Installing distribute.............................................................................................................................................................................................done. 04:48:52 INFO - Installing pip.................done. 04:48:52 INFO - Return code: 0 04:48:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7bca9eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, '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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:48:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:48:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:52 INFO - 'HOME': '/Users/cltbld', 04:48:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:52 INFO - 'LOGNAME': 'cltbld', 04:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:52 INFO - 'MOZ_NO_REMOTE': '1', 04:48:52 INFO - 'NO_EM_RESTART': '1', 04:48:52 INFO - 'PAGER': '/bin/cat', 04:48:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:52 INFO - 'PWD': '/builds/slave/test', 04:48:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:52 INFO - 'SHELL': '/bin/bash', 04:48:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:48:52 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:48:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:52 INFO - 'USER': 'cltbld', 04:48:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:52 INFO - 'XPC_FLAGS': '0x0', 04:48:52 INFO - 'XPC_SERVICE_NAME': '0', 04:48:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:48:52 INFO - Cleaning up... 04:48:52 INFO - Return code: 0 04:48:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7bca9eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, '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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:48:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:48:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:52 INFO - 'HOME': '/Users/cltbld', 04:48:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:52 INFO - 'LOGNAME': 'cltbld', 04:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:52 INFO - 'MOZ_NO_REMOTE': '1', 04:48:52 INFO - 'NO_EM_RESTART': '1', 04:48:52 INFO - 'PAGER': '/bin/cat', 04:48:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:52 INFO - 'PWD': '/builds/slave/test', 04:48:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:52 INFO - 'SHELL': '/bin/bash', 04:48:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:48:52 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:48:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:52 INFO - 'USER': 'cltbld', 04:48:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:52 INFO - 'XPC_FLAGS': '0x0', 04:48:52 INFO - 'XPC_SERVICE_NAME': '0', 04:48:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:53 INFO - Downloading/unpacking psutil>=3.1.1 04:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:48:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:55 INFO - Installing collected packages: psutil 04:48:55 INFO - Running setup.py install for psutil 04:48:56 INFO - building 'psutil._psutil_osx' extension 04:48:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:48:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:48:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:48:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:48:56 INFO - building 'psutil._psutil_posix' extension 04:48:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:48:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:48:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:48:56 INFO - ^ 04:48:56 INFO - 1 warning generated. 04:48:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:48:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:57 INFO - Successfully installed psutil 04:48:57 INFO - Cleaning up... 04:48:57 INFO - Return code: 0 04:48:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7bca9eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, '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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:48: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 mozsystemmonitor==0.3 04:48:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:48:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:57 INFO - 'HOME': '/Users/cltbld', 04:48:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:57 INFO - 'LOGNAME': 'cltbld', 04:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:57 INFO - 'MOZ_NO_REMOTE': '1', 04:48:57 INFO - 'NO_EM_RESTART': '1', 04:48:57 INFO - 'PAGER': '/bin/cat', 04:48:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:57 INFO - 'PWD': '/builds/slave/test', 04:48:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:57 INFO - 'SHELL': '/bin/bash', 04:48:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:48:57 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:48:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:57 INFO - 'USER': 'cltbld', 04:48:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:57 INFO - 'XPC_FLAGS': '0x0', 04:48:57 INFO - 'XPC_SERVICE_NAME': '0', 04:48:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:48:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:48:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:48:59 INFO - Installing collected packages: mozsystemmonitor 04:48:59 INFO - Running setup.py install for mozsystemmonitor 04:49:00 INFO - Successfully installed mozsystemmonitor 04:49:00 INFO - Cleaning up... 04:49:00 INFO - Return code: 0 04:49:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7bca9eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, '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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:49:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:00 INFO - 'HOME': '/Users/cltbld', 04:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:00 INFO - 'LOGNAME': 'cltbld', 04:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:00 INFO - 'MOZ_NO_REMOTE': '1', 04:49:00 INFO - 'NO_EM_RESTART': '1', 04:49:00 INFO - 'PAGER': '/bin/cat', 04:49:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:00 INFO - 'PWD': '/builds/slave/test', 04:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:00 INFO - 'SHELL': '/bin/bash', 04:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:49:00 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:00 INFO - 'USER': 'cltbld', 04:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:00 INFO - 'XPC_FLAGS': '0x0', 04:49:00 INFO - 'XPC_SERVICE_NAME': '0', 04:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:00 INFO - Downloading/unpacking jsonschema==2.5.1 04:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:49:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49: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 04:49:03 INFO - Downloading functools32-3.2.3-2.tar.gz 04:49:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:49:03 INFO - warning: no files found matching '*.txt' 04:49:03 INFO - no previously-included directories found matching 'build' 04:49:03 INFO - no previously-included directories found matching 'dist' 04:49:03 INFO - no previously-included directories found matching '.git*' 04:49:03 INFO - Installing collected packages: jsonschema, functools32 04:49:03 INFO - Running setup.py install for functools32 04:49:03 INFO - warning: no files found matching '*.txt' 04:49:03 INFO - no previously-included directories found matching 'build' 04:49:03 INFO - no previously-included directories found matching 'dist' 04:49:03 INFO - no previously-included directories found matching '.git*' 04:49:03 INFO - Successfully installed jsonschema functools32 04:49:03 INFO - Cleaning up... 04:49:03 INFO - Return code: 0 04:49:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7bca9eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, '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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:03 INFO - 'HOME': '/Users/cltbld', 04:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:03 INFO - 'LOGNAME': 'cltbld', 04:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:03 INFO - 'MOZ_NO_REMOTE': '1', 04:49:03 INFO - 'NO_EM_RESTART': '1', 04:49:03 INFO - 'PAGER': '/bin/cat', 04:49:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:03 INFO - 'PWD': '/builds/slave/test', 04:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:03 INFO - 'SHELL': '/bin/bash', 04:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:49:03 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:03 INFO - 'USER': 'cltbld', 04:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:03 INFO - 'XPC_FLAGS': '0x0', 04:49:03 INFO - 'XPC_SERVICE_NAME': '0', 04:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:03 INFO - Downloading/unpacking blobuploader==1.2.4 04:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:06 INFO - Downloading blobuploader-1.2.4.tar.gz 04:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:49:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:49:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:07 INFO - Downloading docopt-0.6.1.tar.gz 04:49:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:49:07 INFO - Installing collected packages: blobuploader, requests, docopt 04:49:07 INFO - Running setup.py install for blobuploader 04:49:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:49:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:49:07 INFO - Running setup.py install for requests 04:49:08 INFO - Running setup.py install for docopt 04:49:08 INFO - Successfully installed blobuploader requests docopt 04:49:08 INFO - Cleaning up... 04:49:08 INFO - Return code: 0 04:49:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7bca9eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, '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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:49:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:49:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:08 INFO - 'HOME': '/Users/cltbld', 04:49:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:08 INFO - 'LOGNAME': 'cltbld', 04:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:08 INFO - 'MOZ_NO_REMOTE': '1', 04:49:08 INFO - 'NO_EM_RESTART': '1', 04:49:08 INFO - 'PAGER': '/bin/cat', 04:49:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:08 INFO - 'PWD': '/builds/slave/test', 04:49:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:08 INFO - 'SHELL': '/bin/bash', 04:49:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:49:08 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:49:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:08 INFO - 'USER': 'cltbld', 04:49:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:08 INFO - 'XPC_FLAGS': '0x0', 04:49:08 INFO - 'XPC_SERVICE_NAME': '0', 04:49:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:49:08 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-jF99Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:49:08 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-JZfOk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:49:08 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-EDNDFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-gRkJ12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-fNhcGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-TNyZOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-NkAxsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-xtJsT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-lfDSu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-F9JFUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-0V7QUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-teBmwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:49:09 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-dLgIUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:49:10 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-AGXPeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:10 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-8uGRra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:49:10 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-l0lkav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:49:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:49:10 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-h1hDNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:49:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:49:10 INFO - Running setup.py install for manifestparser 04:49:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:49:10 INFO - Running setup.py install for mozcrash 04:49:10 INFO - Running setup.py install for mozdebug 04:49:10 INFO - Running setup.py install for mozdevice 04:49:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Running setup.py install for mozfile 04:49:11 INFO - Running setup.py install for mozhttpd 04:49:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Running setup.py install for mozinfo 04:49:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Running setup.py install for mozInstall 04:49:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Running setup.py install for mozleak 04:49:11 INFO - Running setup.py install for mozlog 04:49:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:49:11 INFO - Running setup.py install for moznetwork 04:49:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:49:12 INFO - Running setup.py install for mozprocess 04:49:12 INFO - Running setup.py install for mozprofile 04:49:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:49:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:49:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:49:12 INFO - Running setup.py install for mozrunner 04:49:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:49:12 INFO - Running setup.py install for mozscreenshot 04:49:12 INFO - Running setup.py install for moztest 04:49:12 INFO - Running setup.py install for mozversion 04:49:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:49:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:49:13 INFO - Cleaning up... 04:49:13 INFO - Return code: 0 04:49:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7bca9eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, '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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:49:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:49:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:13 INFO - 'HOME': '/Users/cltbld', 04:49:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:13 INFO - 'LOGNAME': 'cltbld', 04:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:13 INFO - 'MOZ_NO_REMOTE': '1', 04:49:13 INFO - 'NO_EM_RESTART': '1', 04:49:13 INFO - 'PAGER': '/bin/cat', 04:49:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:13 INFO - 'PWD': '/builds/slave/test', 04:49:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:13 INFO - 'SHELL': '/bin/bash', 04:49:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:49:13 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:49:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:13 INFO - 'USER': 'cltbld', 04:49:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:13 INFO - 'XPC_FLAGS': '0x0', 04:49:13 INFO - 'XPC_SERVICE_NAME': '0', 04:49:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:49:13 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-W9MgxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:49:13 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-cQVuGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:49:13 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-DH7ROU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:49:13 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-k1N9gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:49:13 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-zsR8GG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:49:13 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-HHGxPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:49:13 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-zp7iPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:49:14 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-NLwGPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:49:14 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-aHBziV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:49:14 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-6gMw3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:49:14 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-Ncafh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:49:14 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-RF6Z1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:49:14 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-nXbN3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:49:14 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-123Ck8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:14 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-QghVJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:49:14 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-DogxpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:49:15 INFO - Running setup.py (path:/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/pip-kdtZ2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:49:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:49:17 INFO - Downloading blessings-1.6.tar.gz 04:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:49:17 INFO - Installing collected packages: blessings 04:49:17 INFO - Running setup.py install for blessings 04:49:17 INFO - Successfully installed blessings 04:49:17 INFO - Cleaning up... 04:49:18 INFO - Return code: 0 04:49:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:49:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:49:18 INFO - Reading from file tmpfile_stdout 04:49:18 INFO - Current package versions: 04:49:18 INFO - blessings == 1.6 04:49:18 INFO - blobuploader == 1.2.4 04:49:18 INFO - docopt == 0.6.1 04:49:18 INFO - functools32 == 3.2.3-2 04:49:18 INFO - jsonschema == 2.5.1 04:49:18 INFO - manifestparser == 1.1 04:49:18 INFO - mozInstall == 1.12 04:49:18 INFO - mozcrash == 0.17 04:49:18 INFO - mozdebug == 0.1 04:49:18 INFO - mozdevice == 0.48 04:49:18 INFO - mozfile == 1.2 04:49:18 INFO - mozhttpd == 0.7 04:49:18 INFO - mozinfo == 0.9 04:49:18 INFO - mozleak == 0.1 04:49:18 INFO - mozlog == 3.2 04:49:18 INFO - moznetwork == 0.27 04:49:18 INFO - mozprocess == 0.23 04:49:18 INFO - mozprofile == 0.28 04:49:18 INFO - mozrunner == 6.12 04:49:18 INFO - mozscreenshot == 0.1 04:49:18 INFO - mozsystemmonitor == 0.3 04:49:18 INFO - moztest == 0.7 04:49:18 INFO - mozversion == 1.4 04:49:18 INFO - psutil == 3.1.1 04:49:18 INFO - requests == 1.2.3 04:49:18 INFO - wsgiref == 0.1.2 04:49:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7bca9eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, '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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:18 INFO - 'HOME': '/Users/cltbld', 04:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:18 INFO - 'LOGNAME': 'cltbld', 04:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:18 INFO - 'MOZ_NO_REMOTE': '1', 04:49:18 INFO - 'NO_EM_RESTART': '1', 04:49:18 INFO - 'PAGER': '/bin/cat', 04:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:18 INFO - 'PWD': '/builds/slave/test', 04:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:18 INFO - 'SHELL': '/bin/bash', 04:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:49:18 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:18 INFO - 'USER': 'cltbld', 04:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:18 INFO - 'XPC_FLAGS': '0x0', 04:49:18 INFO - 'XPC_SERVICE_NAME': '0', 04:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:49:18 INFO - Cleaning up... 04:49:18 INFO - Return code: 0 04:49:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:49:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c6c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c75c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7bca9eb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, '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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:49:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:18 INFO - 'HOME': '/Users/cltbld', 04:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:18 INFO - 'LOGNAME': 'cltbld', 04:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:18 INFO - 'MOZ_NO_REMOTE': '1', 04:49:18 INFO - 'NO_EM_RESTART': '1', 04:49:18 INFO - 'PAGER': '/bin/cat', 04:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:18 INFO - 'PWD': '/builds/slave/test', 04:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:18 INFO - 'SHELL': '/bin/bash', 04:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:49:18 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:18 INFO - 'USER': 'cltbld', 04:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:18 INFO - 'XPC_FLAGS': '0x0', 04:49:18 INFO - 'XPC_SERVICE_NAME': '0', 04:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:49:18 INFO - Cleaning up... 04:49:18 INFO - Return code: 0 04:49:18 INFO - Running post-action listener: _resource_record_post_action 04:49:18 INFO - Running post-action listener: _start_resource_monitoring 04:49:18 INFO - Starting resource monitoring. 04:49:18 INFO - ##### 04:49:18 INFO - ##### Finished create-virtualenv step (success) 04:49:18 INFO - ##### 04:49:18 INFO - ##### 04:49:18 INFO - ##### Running install step. 04:49:18 INFO - ##### 04:49:18 INFO - Running pre-action listener: _resource_record_pre_action 04:49:18 INFO - Running main action method: install 04:49:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:49:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:49:19 INFO - Reading from file tmpfile_stdout 04:49:19 INFO - Detecting whether we're running mozinstall >=1.0... 04:49:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:49:19 INFO - Reading from file tmpfile_stdout 04:49:19 INFO - Output received: 04:49:19 INFO - Usage: mozinstall [options] installer 04:49:19 INFO - Options: 04:49:19 INFO - -h, --help show this help message and exit 04:49:19 INFO - -d DEST, --destination=DEST 04:49:19 INFO - Directory to install application into. [default: 04:49:19 INFO - "/builds/slave/test"] 04:49:19 INFO - --app=APP Application being installed. [default: firefox] 04:49:19 INFO - mkdir: /builds/slave/test/build/application 04:49:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:49:53 INFO - Reading from file tmpfile_stdout 04:49:53 INFO - Output received: 04:49:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:49:53 INFO - Running post-action listener: _resource_record_post_action 04:49:53 INFO - ##### 04:49:53 INFO - ##### Finished install step (success) 04:49:53 INFO - ##### 04:49:53 INFO - ##### 04:49:53 INFO - ##### Running run-tests step. 04:49:53 INFO - ##### 04:49:53 INFO - Running pre-action listener: _resource_record_pre_action 04:49:53 INFO - Running main action method: run_tests 04:49:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:53 INFO - 2016-07-12 04:49:53.286 screenresolution[1634:7346] starting screenresolution argv=screenresolution get 04:49:53 INFO - 2016-07-12 04:49:53.289 screenresolution[1634:7346] Display 0: 1600x1200x32@60 04:49:53 INFO - 2016-07-12 04:49:53.294 screenresolution[1635:7348] starting screenresolution argv=screenresolution list 04:49:53 INFO - Available Modes on Display 0 04:49:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:53 INFO - Intel Iris: 04:49:53 INFO - Chipset Model: Intel Iris 04:49:53 INFO - Type: GPU 04:49:53 INFO - Bus: Built-In 04:49:53 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:53 INFO - Vendor: Intel (0x8086) 04:49:53 INFO - Device ID: 0x0a2e 04:49:53 INFO - Revision ID: 0x0009 04:49:53 INFO - Displays: 04:49:53 INFO - Display: 04:49:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:53 INFO - Display Serial Number: 1600x1200 60 04:49:53 INFO - Main Display: Yes 04:49:53 INFO - Mirror: Off 04:49:53 INFO - Online: Yes 04:49:53 INFO - Rotation: Supported 04:49:53 INFO - Return code: 0 04:49:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:49:53 INFO - Python 2.7.3 04:49:53 INFO - Return code: 0 04:49:53 INFO - grabbing minidump binary from tooltool 04:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:49:53 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c62bd30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c623b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:49:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:49:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:49:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:49:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:49:53 INFO - Return code: 0 04:49:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:49:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:49:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:49:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:49:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:49:53 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/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:49:53 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/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0237 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:49:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eKcD9KQlqe/Render', 04:49:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:53 INFO - 'HOME': '/Users/cltbld', 04:49:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:53 INFO - 'LOGNAME': 'cltbld', 04:49:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:49:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:53 INFO - 'MOZ_NO_REMOTE': '1', 04:49:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:49:53 INFO - 'NO_EM_RESTART': '1', 04:49:53 INFO - 'PAGER': '/bin/cat', 04:49:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:53 INFO - 'PWD': '/builds/slave/test', 04:49:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:49:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:53 INFO - 'SHELL': '/bin/bash', 04:49:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.I4rd5L0gs4/Listeners', 04:49:53 INFO - 'TMPDIR': '/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/', 04:49:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:53 INFO - 'USER': 'cltbld', 04:49:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:53 INFO - 'XPC_FLAGS': '0x0', 04:49:53 INFO - 'XPC_SERVICE_NAME': '0', 04:49:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:53 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/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0237', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:49:53 INFO - mozversion application_buildid: 20160712030118 04:49:53 INFO - mozversion application_changeset: 6bd7cb39abaf55225b932acce3a2d8c6320c2273 04:49:53 INFO - mozversion application_display_name: Nightly 04:49:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:49:53 INFO - mozversion application_name: Firefox 04:49:53 INFO - mozversion application_remotingname: firefox 04:49:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:49:53 INFO - mozversion application_vendor: Mozilla 04:49:53 INFO - mozversion application_version: 50.0a1 04:49:53 INFO - mozversion platform_buildid: 20160712030118 04:49:53 INFO - mozversion platform_changeset: 6bd7cb39abaf55225b932acce3a2d8c6320c2273 04:49:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:49:53 INFO - mozversion platform_version: 50.0a1 04:49:53 INFO - using testdate: 1468324193 04:49:53 INFO - actual date: 1468324193 04:49:53 INFO - starting webserver on 'localhost:49271' 04:49:53 INFO - SUITE-START | Running 2 tests 04:49:53 INFO - TEST-START | dromaeo_css 04:49:53 INFO - operating with platform_type : mac_ 04:49:53 INFO - Initialising browser for dromaeo_css test... 04:49:53 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpndM3CJ/profile http://localhost:49271/getInfo.html) 04:49:56 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 04:49:56 INFO - PROCESS | 1645 | colorDepth:24 04:49:56 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 04:49:56 INFO - PROCESS | 1645 | __metrics 04:49:56 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:49:56 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:49:56 INFO - TEST-INFO | 1645: exit 0 04:49:56 INFO - Browser initialized. 04:49:56 INFO - Running cycle 1/1 for dromaeo_css test... 04:49:56 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmpndM3CJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:09 INFO - PROCESS | 1647 | RSS: Main: 243511296 04:50:09 INFO - PROCESS | 1647 | 04:51:00 INFO - PROCESS | 1647 | 1468324260052 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:53:11 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 04:53:13 INFO - PROCESS | 1647 | RSS: Main: 382300160 04:53:13 INFO - PROCESS | 1647 | 04:56:16 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 04:56:17 INFO - PROCESS | 1647 | RSS: Main: 376434688 04:56:17 INFO - PROCESS | 1647 | 04:59:19 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 04:59:20 INFO - PROCESS | 1647 | RSS: Main: 377573376 04:59:20 INFO - PROCESS | 1647 | 05:02:22 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:02:22 INFO - PROCESS | 1647 | RSS: Main: 369803264 05:02:22 INFO - PROCESS | 1647 | 05:05:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:05:26 INFO - PROCESS | 1647 | RSS: Main: 340561920 05:05:26 INFO - PROCESS | 1647 | 05:08:30 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:08:30 INFO - PROCESS | 1647 | RSS: Main: 403083264 05:08:30 INFO - PROCESS | 1647 | 05:08:30 INFO - PROCESS | 1647 | __start_tp_report 05:08:30 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 05:08:30 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 05:08:30 INFO - PROCESS | 1647 | |i|pagename|runs| 05:08:30 INFO - PROCESS | 1647 | |0;dojo.html;3400.3879301725688;3413.368526822538;3418.8461519231623;3438.277961628058;3476.235228249786;5670.461306175917;5784.103463963082;5818.098194779811;5892.55805814564;5900.88198236035;5805.535557155428;5852;5853.5902486825835;5862.237909071825;5876.76492940283;5233.162693968975;5259.263703081571;5291.391489978644;5311.521963023327;5351.892962140755;5271.15661494161;5323.041852466555;5324.946750532503;5355.892882142355;5370.462953704618;5284.735763211843;5285.022270879899;5308.362996440433;5338.706371149585;5356.54469370104;5361.624686271982;5370.7046112463795;5380.515753582157;5381.838544843661;5402.270693456372;4550.840720574764;5074.923876141861;5192.1952097424955;5211.765470553834;5300.416954135042;5763.971180144114;5765.884682306372;5782.2483077199695;5787.421257874221;5830.82507524775;6261.46777523911;6262.029385445263;6280.089387038899;6281.937180628181;6283.717232724538;6159.599626024339;6230.719617617218;6302.684865756693;6321.620702757803;6326.114343991799;5948.078047902625;5953.940460595425;5967.433093856077;5972.402759723993;5983.431574000463;5396.4333744957;6338.176037115146;6349.174607301022;6369.681515924278;6371.936280637135;4948.356713627206;6097.111002522986;6143.757528502154;6229.96885015572;6243.719032643542;5722.7982123753645;6358.809235722936;6364.840878978062;6365.49076073913;6375.203099612549;5917.911231331534;6257.436830685169;6260.154879091356;6289.7484100636375;6294.339094395023;6125.784909797739;6187.257529096537;6210.285817130997;6264.68676566179;6302.054691796176;5017.473165317662;5036.79852805891;5046.84859454217;5078.314427552233;5118.360204974379;4491.011977364975;4499.820007199741;4518.277075667877;4541.68208225421;4556.202664533694;5886.087656413263;5894.852628684232;5895.35151133375;5905.822825315161;5952.553558483131;6165.229346331708;6225.564210505312;6259.311475737665;6262.624242545462;6300.086487459293;5748.856278593069;5796.710164491843;5812.505936995318;5833.125031245348;5870.941290587125;5494.780208791603;5506.174073888948;5508.807191748269;5514.696691681996;5520.475554822286;6258.968705156445;6300.8424789379715;6314.305426403;6318.399752023551;6422.42198202164;6351.2060992375955;6361.904571431525;6366.554341196257;6373.426391624776;6381.266154392304;6239.438450539473;6320.525960553069;6398.680065996775;6418.871622567616;6421.261554921244;6219.378062193744;6237.376262373727;6263.217097862767;6322.430981211713;6323.683815809283;5921.200637913998;6264.812055638338;6343.55595108338;6350.1744773181035;6354.523410744305;6004.399560044136;6022.8795424090395;6088.756449741961;6245.156903817927;6255.499560035278;6055.969720151371;6118.9846826261555;6135.018317212141;6139.246828488627;6143.603994442082;5944.3164036136395;5953.315368732651;5958;5978.970105149447;6005.579609427298;6042.214512113397;6099.8243298231955;6135.509159267337;6169.413905678954;6173.32093469709;5905.616134951386;5960.940390596212;5980.282366116094;6025.156478092983;6072.180255665428 05:08:30 INFO - PROCESS | 1647 | |1;ext.html;469.45543169922894;470.18188352267055;516.7442116152503;525.0889706359463;549.2530158983782;30200.244993875163;31760.682393176063;31806.681933180662;31833.885813996487;41669.37495937564;39159.608403915954;40505.58230461935;40947.38578921311;43584.128317433635;43778.34332485007;9063.13900179482;9237.861432078524;9963.551640176209;9963.950180249125;9970.90029099707;7454.701811927516;8983.056779038205;9758.512074396287;9863.161631261351;12017;11949;12298.508059677646;12304.44629991648;12305.83094606011;12337.198082124678;11976.281423114597;12044.939775301067;12065.517379304818;12070.577529786504;12114;8754.824903501958;8914.955425222832;8924.955375223148;9087.954560227223;11995.460204290828;40360.79819600913;40618;40686.59313406858;42795;42841.143177136284;3511.227529943408;3659.688926441229;4119.97940010301;4155.376693495969;4278.850240241608;30614.693853061406;32430.83784581062;32529.83735081333;32557.51163732546;32593.837030814695;3189.1708155879405;3335.6831101045605;3446.7931924084637;3449.413599688059;3450.7929524228375;538061;542788.2860585672;543898;544371;547120;63757.68121159457;64436.677816610616;64458;64461.35538644647;64463;66355.66822165955;66441;66527.34726527309;66549;66681.33318666849;16734.58163545921;16989.830101699074;17005.744913826304;17013.149342532823;17017.574560635836;12971.156874803297;13070.150440221356;13076.476940922446;13079.607611771662;13102.410391532214;16888.746668799977;17282.567935801704;18958.905205473886;19407;19424.8057519423;5119.59043276537;7730.304272615492;7808.726694565777;7809.648565814552;7937.642806073741;902.3728508686531;960.6013504395597;1065.760203954104;1137.436968700482;1145.9583883161858;30661.540076898866;30741.692583073884;30809.537856932166;31518.842405787826;31552.737890484124;1062.7821296634172;1112.7996960547018;1123.702218911989;1164.7612239490884;1168.7019809948408;15870.52388428349;15942.681146377008;15957.521274361789;16188.372336553111;16457.753133703005;718721;729165;729447;731027;731928;51291.48708512942;52020.739896301035;58709.41290587125;59393.812123757285;60948;1293.2693028438903;1320.7980737528803;1414;1414.943402263939;1419.7089596632873;30634.234144145685;31543;31851;32197.03408897737;32521.34957300888;50202;50702.985940281724;50729.49270507395;50747.238791418895;50820.74589627028;455609;471007;471649;472437;686646;26605;26933.730662693906;26947.86526067357;26971.460570788866;27004.594931075655;46852.29721554111;46892.7655361721;47484.7625761869;47500.52499474961;48385.27422088742;38698.80650596729;38907.63823266285;39149;39538.40692389674;39953;38467;38523;38653.226935461695;39546.60453395544;39709.40435893406;37798;38456.423153652155;38518.614813851505;38593;38722.80638596789;30978.831481796056;32266;32574.837125814218;34324;34387;24689.876550617133;24880.37799055038;25248.74751252464;25354.619680705175;25381 05:08:30 INFO - PROCESS | 1647 | |2;jquery.html;365.97621154624966;384.3945785388014;395.50364292812515;396.59349167103716;413.53270803991484;15729.764053539191;20309.390718278457;21409.250676226337;21514.569708605857;21975.890120549415;20810;21702.782972170313;22127.004284807183;22145.66781498279;22226.11095556176;5569.913866795956;5752.223449834271;6015.729292181863;6050;6057.454829065383;5984.3716409777;6041.848953776169;6060.727267272961;6072.180255665495;6092.66490343031;5666.603337766337;5899.085641725539;5904.616199947011;6010.188624535697;6024.759009639609;5901.704914754267;5925.377835327315;5939.70301484924;5940.762369505214;5942.494887934531;4920.655554111214;5174.120399532088;5178.97410512945;5179.171332586806;5184.092783762826;26659.600105998426;26890.327741806417;27045.188644340702;27396.58905116425;27442.313942151406;2294.5066810635626;2306.826987975892;2339.4736184358553;2820.7602353799953;2991.8630920250384;15389.222154570913;16067.035977841368;16387.918060409742;16413.1793410329;16427.425040123537;1799.2623024559998;1833.6241070580631;1936.1287420660758;1959.0694420150458;2043.3665563675308;81488.70323428958;93760;121551.39224303911;122013.38993304977;133792;8869.379143460024;11112.44437778108;11131.944340278409;11155.219134660658;11270.492827822767;13684.52104176349;13786.379612917443;13798.379072941541;13806.378712957941;13825.51610693621;8662.653493860302;8671.956640216758;8732.781680457912;8766.947966244092;8845;8870.911290887138;8881.689140880038;8909.331800115016;8995.460272383678;9025.77435564103;5504.504594586427;6682.331766823376;6682.498812589075;6689.966550167218;6708.792417364921;1837.7610910581539;1873.569079111812;1878.8778729382545;1885.4437940807495;1894.1003023563696;854.9615267312986;955.4888134847962;983.6901376066517;1044.3159730376615;1058.941758203306;19643;22675.773242267365;22964.540709185723;23223.419414514774;23273.301800946;972.8054389122062;974.5176137811828;975.0785507695301;982.4891056650501;1011.1152741351318;10177.389356638625;10974.506147223394;11537.365444900612;12117.87882121185;12167.452464639113;602049;608188;615952;624616;624699.8765006146;96551.55172672415;113406.43296783463;123277.76722232842;128596.07105893231;129545;1008.324422636835;1043.3114144664485;1044.3472829481575;1066.5413872034944;1068.8289873620338;19056.52358691044;19789.901050494656;20380.490487737927;20383.8980805095;20426;52731;53057.73471132619;54164.45835541594;54932.450675492735;55085.898282034934;55251.723741382644;77544;78175.60912195402;78209;78753;8107.22981316774;8123;8138.633761480866;8458.74623761288;8471.449355791854;13903.304834758424;13908.165510069428;13964.860351396763;14095.436182552583;14147.787783183054;11541.711457213636;11669.941650291694;11744.70638234051;11775.52897884075;11788.528458861549;11524.769504609692;11591.362475063952;11801.35092569917;11801.468933898166;12074.758504829679;11883.16817822744;11907.464164112462;11912.166148369532;12067.75864482688;12075.51697932073;8025.117237104031;8030.478018928751;8149.633266502903;8212.548309842778;8330.583470826556;9871.950640246752;11527.8270825936;11689.941550292195;11720.296782192754;11761.176717630025 05:08:30 INFO - PROCESS | 1647 | |3;mootools.html;1188.5364707763968;1547.760097184936;1548.3110016042858;1584.548403704944;1896.497428181532;7761.379089672827;8241;8241.670333186665;8242.381821363399;8245.299149572293;8378.204070613281;8609.913900860989;8610.483370997745;8722.563871806417;8732.214100730933;2313.826463015276;2378.8453750506246;2383.7139543254834;2384.2132096408145;2384.523095380922;2332.7317358503733;2351.3063646224405;2372.525494901018;2373.95252094958;2377.9881100594557;2247.2696373678536;2263.7396699379533;2272.409173614864;2283.5889539882896;2287.279506955312;2322.4890524084676;2351.2593533037125;2353.1411034972284;2361.1027809432476;2375;2349.800266977309;2354.117206047732;2355.4464700795297;2356.2342238772467;2359.8702071386065;8576.142385761435;8769;8771.210591046773;8778.209961103532;8787.912120878773;4750.097481478508;4920.6801557898625;5145.434002259748;5185.5333020028;5319.202119682079;3458.5503884494856;3492.4935884296888;3605.801680907549;3615.385384484644;3624.27514497099;1003.19744204636;1047.7694907120422;1061.888501707325;1096.0628662493612;1107.778444311141;11575.479103440366;11699.23954942926;11729.941350293278;11735.7066073348;11758.353290569015;4651.62786977041;4651.674382793172;4806.831760888352;4815.422149342101;4823.059503396804;10026.548805303779;10026.64906728261;10030;10053.994600539887;10054.396736195851;5369.0872551665525;5369.140937449989;5377;5392.460753924576;5407.702576358338;4862.635302352338;4880.853574392774;4928.8767780805765;4929.75351232437;4930.704157750546;3787.034306251888;3795.848166073326;3825.0628595993603;3889.7860617665733;3915.7454765440157;1052.55792567122;1218.7867123253395;1229.692576855786;1232.211384713784;1250.6748245456065;868.5179725252419;930.9953450232706;944.8818897637796;962.5187406296851;980.3333733061587;5732.426757324234;5958.344582095966;6056.788012419544;6105.236845394325;6205.627662340274;432.8506665200501;441.15738938627067;446.76321549578773;449.45391349510504;456.4363011680572;4064.227796718614;4071.2671719090254;4117.629413352813;4139.482564679415;4163.084121493267;10244.078032976919;10420.843687344695;10494.580216791242;10642.680719578426;10642.89357106419;10618.628348007782;10628.309159904582;10634.840477392845;10635.893641063645;10643.52104155315;7783.4162437817395;8103.716369927024;8151.5924203790755;8205;8252.917470825336;6080.361562035922;6092.116643086639;6197.349278325892;6212;6221.657808820377;9984.35101718386;9997.450140242356;10012.899871001488;10022.298439109192;10025.448600327052;9580.329376943548;9899.752506187402;10234.795304094025;10333.379997200193;10373.118284945562;6146.815595532141;7264.382527485223;7528.360089392463;7536.547807131589;7542.4720269582795;8183.79540511492;8294.502329860228;8340.29107525867;8356.916430835614;8374.623141958504;7490.70037198506;7509.324160825552;7565.962170189114;7635.121960974337;7640.426967977312;7251.057362542836;7267.527610705287;7304;7406.666699998621;7413.962930185315;6980.162380514168;7023.47323950716;7062.223155452999;7071.7878463646175;7091.971664108572;3956.564777874374;5021.593953692972;5023.723695196799;5047.116754568009;5161.174212126042;4980.277859710394;5021.07110184617;5022.74886255693;5098.566621837185;5140.3831540215415 05:08:30 INFO - PROCESS | 1647 | |4;prototype.html;287.4093737369707;300.9924751881204;305.3511288511912;315.142811552577;318.77048525061946;4871.439784424792;5344.4922732340465;5470.702305031891;5643.774249030044;5670.546356291492;4978.751062446872;5317.441668624797;5427.891442171154;5521.861953451155;5565.2486914266565;4194.895127621803;4244.851430199944;4245.724027938174;4252.064545799919;4263.829446822124;3739.5699494558066;4008.1983603279314;4089.977505623594;4255.702100852942;4278.432940235849;3360.395128876801;4222.218889505451;4228.6617070634275;4238.173556156551;4292.849750258742;4044.5146582410007;4204.684648651363;4221;4229.344451610005;4238.618524332809;3551.4672799080085;3682.2819550187724;3697.186618943828;3702.500162478058;3730.9180337702032;5052.494750524955;5282.822833623071;5401.864953376158;5419.132938729785;5497.47773961473;3891.805409729502;4042.4946881639794;4635.629149668019;4884.853454396374;4927.457979622239;4702.788374523155;5851.882962340772;5864.953182304239;5865.794697185621;5878.354740366852;3379.678930501598;4041.6864519031433;4054.6553542948886;4078.163976384834;4100.712950093494;4878.438979517401;6082.482988945945;6089.604175728564;6091.604045737013;6097.451229389376;3238.8704451821663;3871.812425979369;3940.704447166474;3972.6424621784176;3983.4024896265214;3373.4939759036342;3862.8454861805776;3997.200559888034;4075.3275709508193;4076;4598.471175814757;4714.976425117852;4718.693284936537;4785.372973189133;4805.13507568641;5076.5632496401195;5842.072116201643;5847.26909136358;5858.619189752652;5973.163757074013;1251.7997120460682;1308.3065975033057;1361.9455221791216;1384.6607581142482;1393.5610282760897;708.348319546019;825.1377310710322;829.6515463505342;843.4306842881031;849.2017503546647;4248.326840475453;4608.239640459354;4609.331646911246;4624.8900263937085;4666.346711460398;4341.176705783579;5036.949630503721;5069.290299358093;5074.720890350993;5083.949160508421;3766.3597188478025;4544.7500387479;4545.5908968193025;4563.338315944169;4626.3860918580895;2547.592385218356;2696.393311504896;2709.6071069694785;2712.0507822262052;2713.3623598454346;4365.75988320639;4773.570378665937;4793.448753393334;4794.544518270759;5314.654547454403;4948.950510494849;4952.653314268039;5169.663971841818;5215.426303106655;5255.474452554714;4361;5131.7434128293435;5193.558547523503;5239.266502689627;5252.395974462909;4316.482022157235;5034.798608055783;5064.632549211766;5065.936153407825;5103.668261562986;4585.3951117108745;4645.3496510487885;4840.685355451885;4992.126377883928;5008.645936409882;4536.4783049948355;4580.908381832412;4797.464811260364;5154.299081303086;5219.5041471060185;2946.9557956630238;3385.9830700846337;3492.9521143657307;3511.841967111537;3745.0824547985917;5031.396232452129;5530.751116199861;5650.547956163579;5870.9412905870395;6036.8188954331445;5331.786728530816;5526.281583394133;5833.949889019847;5854.590178687451;5928.851778705567;4720.693154944917;5779.786244888621;5844.12338149281;5934.970325148347;5961.91057134152;4971.353724015855;5314.4951229633125;5774.48029677331;5783.971080144573;5803.504493067852;4908.263760435963;5868.501177399969;5964.135200395832;5965.70171491415;5973.313068996948;5039.546440820343;5461.945380546303;5799.971000144972;5824.388439213991;5862.912056319074 05:08:30 INFO - PROCESS | 1647 | |5;yui.html;742.1910117971411;802.2539038694016;814.7881550796791;823.6581818545305;859.8667206582982;14973.679079742644;15578.766318505232;16237;16244.512664620066;16296.429624963128;14732.6316842079;14860.925695371536;14901.478448254313;14910.329035193443;14913.627159321022;547.8249155561318;747.383408687832;885.2431171348485;903.286403741047;907.8728977943093;839.588601585225;920.3373571028882;920.4431319051978;921.345844450441;922.077922077922;970.5098925042844;989.3124278626358;996.267743208741;996.8903420623761;997.840345544713;762.4129420346302;945.3666043750635;985.4875464758286;988.9851652225186;991.420019288717;727.8435136445637;729.1723893380997;729.3836707981746;729.5148726097138;734.566605702638;14978.026428282126;14990.925045374812;15003.624909377244;15020.624484387869;15040.398384064625;13788.586342409644;13902.16587004783;13978.930105349407;14091.718165636732;14174;12896.742065158738;12971.156874803202;13009.34953252343;13158.276294803782;13412.727652921969;4534.229181039232;4605.953940460586;4628.282616194495;4636.09596128755;4789.688670236424;108002;108916.36625450624;117377.4131129339;118152;118301;11057;11189.49647265875;11213.383263920563;11277.210595258253;11633.76732465346;18294.451166465027;22116.668249976265;22319.330420087423;22337;22413.103475860782;7982.920170798334;8047.597620118971;8088.029436467662;8171.79570510742;8175.754727358189;4330.155619654201;4438.1567502174485;4543.727376357429;4641.257398816173;4695.624350051988;3127.202563346332;3570.357335679603;3579.821008949594;3581.015220814245;3586.3544561979093;622.6015350175891;640.7981485831948;650.0996120373222;659.2089492608889;661.206552137437;508.6134537751262;534.398801348483;632.4434497642045;655.5181941273161;657.3032585459429;8643.30853531716;8644.178803013703;8731.388802783871;8766.342524310574;8852.8672069919;506.4353246130538;547.3158551810237;576.2221001647742;595.9463648271676;626.9341719119518;4206.453161089039;4299.312110062375;4302.397664326997;4345.782710864444;4369.934450983238;119267.40366298286;139074;147361;150640;151718.24140879445;120487;122177;123022;124458;127336.72663273249;106526.46736766267;106815.93184068215;108756.40544931883;109063.99999999841;111543.44228278806;6827.283135270725;8060.959695201486;8257.79355516117;8377.58112094405;8481.27909127731;55504.44495554993;57229;57330;58817.82364352774;62521.37478625155;59267.70366148141;89360.5531972336;91801.54099229728;91865.54067229888;92694.51138420515;10463.970375974315;13611;13652.385642645906;13676.179429234278;14215.715685686022;14712.337944792296;15049.623759405664;15152.848471515585;15346.616334591725;15924.761128582848;14015.789763153356;14293.213873236657;14330.928345358207;14370.425182992563;14424.278786060864;14008.859911400756;14155.575332740034;14224.71550568962;14346.856531434552;14364.209968451836;13427.218888030318;13795.448182073007;14553.636159096108;14566.85433145655;14579.635509112357;11187.72030699239;11216.55133794639;11244.66266012021;11308.321500709984;11443.656690299304;10557.577696892038;10844.620438284623;11024.944875275572;11291.435428228391;11326 05:08:30 INFO - PROCESS | 1647 | __end_tp_report 05:08:30 INFO - PROCESS | 1647 | __start_cc_report 05:08:30 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1406 05:08:30 INFO - PROCESS | 1647 | __end_cc_report 05:08:30 INFO - PROCESS | 1647 | __startTimestamp1468325310253__endTimestamp 05:08:30 INFO - PROCESS | 1647 | 05:08:30 INFO - PROCESS | 1647 | ------- Summary: start ------- 05:08:30 INFO - PROCESS | 1647 | Number of tests: 6 05:08:30 INFO - PROCESS | 1647 | 05:08:30 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5802.00 Median:5967.43 stddev:630.13 (10.6%) stddev-sans-first:603.24 05:08:30 INFO - PROCESS | 1647 | Values: 3400.4 3413.4 3418.8 3438.3 3476.2 5670.5 5784.1 5818.1 5892.6 5900.9 5805.5 5852.0 5853.6 5862.2 5876.8 5233.2 5259.3 5291.4 5311.5 5351.9 5271.2 5323.0 5324.9 5355.9 5370.5 5284.7 5285.0 5308.4 5338.7 5356.5 5361.6 5370.7 5380.5 5381.8 5402.3 4550.8 5074.9 5192.2 5211.8 5300.4 5764.0 5765.9 5782.2 5787.4 5830.8 6261.5 6262.0 6280.1 6281.9 6283.7 6159.6 6230.7 6302.7 6321.6 6326.1 5948.1 5953.9 5967.4 5972.4 5983.4 5396.4 6338.2 6349.2 6369.7 6371.9 4948.4 6097.1 6143.8 6230.0 6243.7 5722.8 6358.8 6364.8 6365.5 6375.2 5917.9 6257.4 6260.2 6289.7 6294.3 6125.8 6187.3 6210.3 6264.7 6302.1 5017.5 5036.8 5046.8 5078.3 5118.4 4491.0 4499.8 4518.3 4541.7 4556.2 5886.1 5894.9 5895.4 5905.8 5952.6 6165.2 6225.6 6259.3 6262.6 6300.1 5748.9 5796.7 5812.5 5833.1 5870.9 5494.8 5506.2 5508.8 5514.7 5520.5 6259.0 6300.8 6314.3 6318.4 6422.4 6351.2 6361.9 6366.6 6373.4 6381.3 6239.4 6320.5 6398.7 6418.9 6421.3 6219.4 6237.4 6263.2 6322.4 6323.7 5921.2 6264.8 6343.6 6350.2 6354.5 6004.4 6022.9 6088.8 6245.2 6255.5 6056.0 6119.0 6135.0 6139.2 6143.6 5944.3 5953.3 5958.0 5979.0 6005.6 6042.2 6099.8 6135.5 6169.4 6173.3 5905.6 5960.9 5980.3 6025.2 6072.2 05:08:30 INFO - PROCESS | 1647 | 05:08:30 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:72704.87 Median:38558.00 stddev:161790.07 (419.6%) stddev-sans-first:162153.03 05:08:30 INFO - PROCESS | 1647 | Values: 469.5 470.2 516.7 525.1 549.3 30200.2 31760.7 31806.7 31833.9 41669.4 39159.6 40505.6 40947.4 43584.1 43778.3 9063.1 9237.9 9963.6 9964.0 9970.9 7454.7 8983.1 9758.5 9863.2 12017.0 11949.0 12298.5 12304.4 12305.8 12337.2 11976.3 12044.9 12065.5 12070.6 12114.0 8754.8 8915.0 8925.0 9088.0 11995.5 40360.8 40618.0 40686.6 42795.0 42841.1 3511.2 3659.7 4120.0 4155.4 4278.9 30614.7 32430.8 32529.8 32557.5 32593.8 3189.2 3335.7 3446.8 3449.4 3450.8 538061.0 542788.3 543898.0 544371.0 547120.0 63757.7 64436.7 64458.0 64461.4 64463.0 66355.7 66441.0 66527.3 66549.0 66681.3 16734.6 16989.8 17005.7 17013.1 17017.6 12971.2 13070.2 13076.5 13079.6 13102.4 16888.7 17282.6 18958.9 19407.0 19424.8 5119.6 7730.3 7808.7 7809.6 7937.6 902.4 960.6 1065.8 1137.4 1146.0 30661.5 30741.7 30809.5 31518.8 31552.7 1062.8 1112.8 1123.7 1164.8 1168.7 15870.5 15942.7 15957.5 16188.4 16457.8 718721.0 729165.0 729447.0 731027.0 731928.0 51291.5 52020.7 58709.4 59393.8 60948.0 1293.3 1320.8 1414.0 1414.9 1419.7 30634.2 31543.0 31851.0 32197.0 32521.3 50202.0 50703.0 50729.5 50747.2 50820.7 455609.0 471007.0 471649.0 472437.0 686646.0 26605.0 26933.7 26947.9 26971.5 27004.6 46852.3 46892.8 47484.8 47500.5 48385.3 38698.8 38907.6 39149.0 39538.4 39953.0 38467.0 38523.0 38653.2 39546.6 39709.4 37798.0 38456.4 38518.6 38593.0 38722.8 30978.8 32266.0 32574.8 34324.0 34387.0 24689.9 24880.4 25248.7 25354.6 25381.0 05:08:30 INFO - PROCESS | 1647 | 05:08:30 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:35412.82 Median:14499.54 stddev:102062.34 (703.9%) stddev-sans-first:102314.72 05:08:30 INFO - PROCESS | 1647 | Values: 366.0 384.4 395.5 396.6 413.5 15729.8 20309.4 21409.3 21514.6 21975.9 20810.0 21702.8 22127.0 22145.7 22226.1 5569.9 5752.2 6015.7 6050.0 6057.5 5984.4 6041.8 6060.7 6072.2 6092.7 5666.6 5899.1 5904.6 6010.2 6024.8 5901.7 5925.4 5939.7 5940.8 5942.5 4920.7 5174.1 5179.0 5179.2 5184.1 26659.6 26890.3 27045.2 27396.6 27442.3 2294.5 2306.8 2339.5 2820.8 2991.9 15389.2 16067.0 16387.9 16413.2 16427.4 1799.3 1833.6 1936.1 1959.1 2043.4 81488.7 93760.0 121551.4 122013.4 133792.0 8869.4 11112.4 11131.9 11155.2 11270.5 13684.5 13786.4 13798.4 13806.4 13825.5 8662.7 8672.0 8732.8 8766.9 8845.0 8870.9 8881.7 8909.3 8995.5 9025.8 5504.5 6682.3 6682.5 6690.0 6708.8 1837.8 1873.6 1878.9 1885.4 1894.1 855.0 955.5 983.7 1044.3 1058.9 19643.0 22675.8 22964.5 23223.4 23273.3 972.8 974.5 975.1 982.5 1011.1 10177.4 10974.5 11537.4 12117.9 12167.5 602049.0 608188.0 615952.0 624616.0 624699.9 96551.6 113406.4 123277.8 128596.1 129545.0 1008.3 1043.3 1044.3 1066.5 1068.8 19056.5 19789.9 20380.5 20383.9 20426.0 52731.0 53057.7 54164.5 54932.5 55085.9 55251.7 77544.0 78175.6 78209.0 78753.0 8107.2 8123.0 8138.6 8458.7 8471.4 13903.3 13908.2 13964.9 14095.4 14147.8 11541.7 11669.9 11744.7 11775.5 11788.5 11524.8 11591.4 11801.4 11801.5 12074.8 11883.2 11907.5 11912.2 12067.8 12075.5 8025.1 8030.5 8149.6 8212.5 8330.6 9872.0 11527.8 11689.9 11720.3 11761.2 05:08:30 INFO - PROCESS | 1647 | 05:08:30 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5683.07 Median:4811.13 stddev:3201.32 (66.5%) stddev-sans-first:3192.47 05:08:30 INFO - PROCESS | 1647 | Values: 1188.5 1547.8 1548.3 1584.5 1896.5 7761.4 8241.0 8241.7 8242.4 8245.3 8378.2 8609.9 8610.5 8722.6 8732.2 2313.8 2378.8 2383.7 2384.2 2384.5 2332.7 2351.3 2372.5 2374.0 2378.0 2247.3 2263.7 2272.4 2283.6 2287.3 2322.5 2351.3 2353.1 2361.1 2375.0 2349.8 2354.1 2355.4 2356.2 2359.9 8576.1 8769.0 8771.2 8778.2 8787.9 4750.1 4920.7 5145.4 5185.5 5319.2 3458.6 3492.5 3605.8 3615.4 3624.3 1003.2 1047.8 1061.9 1096.1 1107.8 11575.5 11699.2 11729.9 11735.7 11758.4 4651.6 4651.7 4806.8 4815.4 4823.1 10026.5 10026.6 10030.0 10054.0 10054.4 5369.1 5369.1 5377.0 5392.5 5407.7 4862.6 4880.9 4928.9 4929.8 4930.7 3787.0 3795.8 3825.1 3889.8 3915.7 1052.6 1218.8 1229.7 1232.2 1250.7 868.5 931.0 944.9 962.5 980.3 5732.4 5958.3 6056.8 6105.2 6205.6 432.9 441.2 446.8 449.5 456.4 4064.2 4071.3 4117.6 4139.5 4163.1 10244.1 10420.8 10494.6 10642.7 10642.9 10618.6 10628.3 10634.8 10635.9 10643.5 7783.4 8103.7 8151.6 8205.0 8252.9 6080.4 6092.1 6197.3 6212.0 6221.7 9984.4 9997.5 10012.9 10022.3 10025.4 9580.3 9899.8 10234.8 10333.4 10373.1 6146.8 7264.4 7528.4 7536.5 7542.5 8183.8 8294.5 8340.3 8356.9 8374.6 7490.7 7509.3 7566.0 7635.1 7640.4 7251.1 7267.5 7304.0 7406.7 7414.0 6980.2 7023.5 7062.2 7071.8 7092.0 3956.6 5021.6 5023.7 5047.1 5161.2 4980.3 5021.1 5022.7 5098.6 5140.4 05:08:30 INFO - PROCESS | 1647 | 05:08:30 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4448.64 Median:4801.30 stddev:1366.23 (28.5%) stddev-sans-first:1333.89 05:08:30 INFO - PROCESS | 1647 | Values: 287.4 301.0 305.4 315.1 318.8 4871.4 5344.5 5470.7 5643.8 5670.5 4978.8 5317.4 5427.9 5521.9 5565.2 4194.9 4244.9 4245.7 4252.1 4263.8 3739.6 4008.2 4090.0 4255.7 4278.4 3360.4 4222.2 4228.7 4238.2 4292.8 4044.5 4204.7 4221.0 4229.3 4238.6 3551.5 3682.3 3697.2 3702.5 3730.9 5052.5 5282.8 5401.9 5419.1 5497.5 3891.8 4042.5 4635.6 4884.9 4927.5 4702.8 5851.9 5865.0 5865.8 5878.4 3379.7 4041.7 4054.7 4078.2 4100.7 4878.4 6082.5 6089.6 6091.6 6097.5 3238.9 3871.8 3940.7 3972.6 3983.4 3373.5 3862.8 3997.2 4075.3 4076.0 4598.5 4715.0 4718.7 4785.4 4805.1 5076.6 5842.1 5847.3 5858.6 5973.2 1251.8 1308.3 1361.9 1384.7 1393.6 708.3 825.1 829.7 843.4 849.2 4248.3 4608.2 4609.3 4624.9 4666.3 4341.2 5036.9 5069.3 5074.7 5083.9 3766.4 4544.8 4545.6 4563.3 4626.4 2547.6 2696.4 2709.6 2712.1 2713.4 4365.8 4773.6 4793.4 4794.5 5314.7 4949.0 4952.7 5169.7 5215.4 5255.5 4361.0 5131.7 5193.6 5239.3 5252.4 4316.5 5034.8 5064.6 5065.9 5103.7 4585.4 4645.3 4840.7 4992.1 5008.6 4536.5 4580.9 4797.5 5154.3 5219.5 2947.0 3386.0 3493.0 3511.8 3745.1 5031.4 5530.8 5650.5 5870.9 6036.8 5331.8 5526.3 5833.9 5854.6 5928.9 4720.7 5779.8 5844.1 5935.0 5961.9 4971.4 5314.5 5774.5 5784.0 5803.5 4908.3 5868.5 5964.1 5965.7 5973.3 5039.5 5461.9 5800.0 5824.4 5862.9 05:08:30 INFO - PROCESS | 1647 | 05:08:30 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:24410.25 Median:3348.78 stddev:38417.86 (1147.2%) stddev-sans-first:38484.53 05:08:30 INFO - PROCESS | 1647 | Values: 742.2 802.3 814.8 823.7 859.9 14973.7 15578.8 16237.0 16244.5 16296.4 14732.6 14860.9 14901.5 14910.3 14913.6 547.8 747.4 885.2 903.3 907.9 839.6 920.3 920.4 921.3 922.1 970.5 989.3 996.3 996.9 997.8 762.4 945.4 985.5 989.0 991.4 727.8 729.2 729.4 729.5 734.6 14978.0 14990.9 15003.6 15020.6 15040.4 13788.6 13902.2 13978.9 14091.7 14174.0 12896.7 12971.2 13009.3 13158.3 13412.7 4534.2 4606.0 4628.3 4636.1 4789.7 108002.0 108916.4 117377.4 118152.0 118301.0 11057.0 11189.5 11213.4 11277.2 11633.8 18294.5 22116.7 22319.3 22337.0 22413.1 7982.9 8047.6 8088.0 8171.8 8175.8 4330.2 4438.2 4543.7 4641.3 4695.6 3127.2 3570.4 3579.8 3581.0 3586.4 622.6 640.8 650.1 659.2 661.2 508.6 534.4 632.4 655.5 657.3 8643.3 8644.2 8731.4 8766.3 8852.9 506.4 547.3 576.2 595.9 626.9 4206.5 4299.3 4302.4 4345.8 4369.9 119267.4 139074.0 147361.0 150640.0 151718.2 120487.0 122177.0 123022.0 124458.0 127336.7 106526.5 106815.9 108756.4 109064.0 111543.4 6827.3 8061.0 8257.8 8377.6 8481.3 55504.4 57229.0 57330.0 58817.8 62521.4 59267.7 89360.6 91801.5 91865.5 92694.5 10464.0 13611.0 13652.4 13676.2 14215.7 14712.3 15049.6 15152.8 15346.6 15924.8 14015.8 14293.2 14330.9 14370.4 14424.3 14008.9 14155.6 14224.7 14346.9 14364.2 13427.2 13795.4 14553.6 14566.9 14579.6 11187.7 11216.6 11244.7 11308.3 11443.7 10557.6 10844.6 11024.9 11291.4 11326.0 05:08:30 INFO - PROCESS | 1647 | -------- Summary: end -------- 05:08:30 INFO - PROCESS | 1647 | 05:08:30 INFO - TEST-INFO | 1647: exit 0 05:08:30 INFO - TEST-OK | dromaeo_css | took 1116967ms 05:08:30 INFO - TEST-START | kraken 05:08:30 INFO - operating with platform_type : mac_ 05:08:30 INFO - Initialising browser for kraken test... 05:08:30 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmprf7s6t/profile http://localhost:49271/getInfo.html) 05:08:32 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 05:08:32 INFO - PROCESS | 1656 | colorDepth:24 05:08:32 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 05:08:32 INFO - PROCESS | 1656 | __metrics 05:08:33 INFO - PROCESS | 1656 | 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:08:33 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:08:33 INFO - TEST-INFO | 1656: exit 0 05:08:33 INFO - Browser initialized. 05:08:33 INFO - Running cycle 1/1 for kraken test... 05:08:33 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/T/tmprf7s6t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:08:45 INFO - PROCESS | 1657 | RSS: Main: 243761152 05:08:45 INFO - PROCESS | 1657 | 05:08:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:08:49 INFO - PROCESS | 1657 | RSS: Main: 294064128 05:08:49 INFO - PROCESS | 1657 | 05:08:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:08:52 INFO - PROCESS | 1657 | RSS: Main: 304427008 05:08:52 INFO - PROCESS | 1657 | 05:08:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:08:57 INFO - PROCESS | 1657 | RSS: Main: 389967872 05:08:57 INFO - PROCESS | 1657 | 05:08:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:08:59 INFO - PROCESS | 1657 | RSS: Main: 290287616 05:08:59 INFO - PROCESS | 1657 | 05:09:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:09:02 INFO - PROCESS | 1657 | RSS: Main: 293883904 05:09:02 INFO - PROCESS | 1657 | 05:09:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:09:05 INFO - PROCESS | 1657 | RSS: Main: 330575872 05:09:05 INFO - PROCESS | 1657 | 05:09:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:09:09 INFO - PROCESS | 1657 | RSS: Main: 332775424 05:09:09 INFO - PROCESS | 1657 | 05:09:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:09:12 INFO - PROCESS | 1657 | RSS: Main: 331710464 05:09:12 INFO - PROCESS | 1657 | 05:09:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:09:14 INFO - PROCESS | 1657 | RSS: Main: 311832576 05:09:14 INFO - PROCESS | 1657 | 05:09:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:09:17 INFO - PROCESS | 1657 | RSS: Main: 319332352 05:09:17 INFO - PROCESS | 1657 | 05:09:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:09:20 INFO - PROCESS | 1657 | RSS: Main: 323190784 05:09:20 INFO - PROCESS | 1657 | 05:09:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:09:23 INFO - PROCESS | 1657 | RSS: Main: 324841472 05:09:23 INFO - PROCESS | 1657 | 05:09:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:09:27 INFO - PROCESS | 1657 | RSS: Main: 325238784 05:09:27 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:09:29 INFO - PROCESS | 1657 | RSS: Main: 369246208 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | __start_tp_report 05:09:29 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 05:09:29 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 05:09:29 INFO - PROCESS | 1657 | |i|pagename|runs| 05:09:29 INFO - PROCESS | 1657 | |0;ai-astar;101;114;111;114;113;112;111;113;113;113 05:09:29 INFO - PROCESS | 1657 | |1;audio-beat-detection;143;113;120;191;137;107;127;106;140;135 05:09:29 INFO - PROCESS | 1657 | |2;audio-dft;183;186;185;184;194;258;200;182;182;182 05:09:29 INFO - PROCESS | 1657 | |3;audio-fft;81;70;57;87;59;58;63;61;98;58 05:09:29 INFO - PROCESS | 1657 | |4;audio-oscillator;83;88;92;72;75;68;98;70;67;102 05:09:29 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;86;88;85;87;85;89;86;88;84 05:09:29 INFO - PROCESS | 1657 | |6;imaging-darkroom;92;93;102;93;94;93;93;93;94;93 05:09:29 INFO - PROCESS | 1657 | |7;imaging-desaturate;84;87;86;84;91;84;86;85;86;88 05:09:29 INFO - PROCESS | 1657 | |8;json-parse-financial;56;54;56;54;54;56;54;54;54;56 05:09:29 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;66;74;78;78;73;70;75;71;71;70 05:09:29 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;85;84;82;85;86;85;81;84;87;108 05:09:29 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;138;149;150;133;131;133;157;144;165;136 05:09:29 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;159;164;163;168;223;209;161;162;160;163 05:09:29 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;66;67;66;66;66;66;65;65;65 05:09:29 INFO - PROCESS | 1657 | __end_tp_report 05:09:29 INFO - PROCESS | 1657 | __start_cc_report 05:09:29 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2014 05:09:29 INFO - PROCESS | 1657 | __end_cc_report 05:09:29 INFO - PROCESS | 1657 | __startTimestamp1468325369313__endTimestamp 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | ------- Summary: start ------- 05:09:29 INFO - PROCESS | 1657 | Number of tests: 14 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.84 (3.4%) stddev-sans-first:1.12 05:09:29 INFO - PROCESS | 1657 | Values: 101.0 114.0 111.0 114.0 113.0 112.0 111.0 113.0 113.0 113.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:136.00 stddev:24.79 (18.2%) stddev-sans-first:25.97 05:09:29 INFO - PROCESS | 1657 | Values: 143.0 113.0 120.0 191.0 137.0 107.0 127.0 106.0 140.0 135.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:193.60 Median:185.50 stddev:23.40 (12.6%) stddev-sans-first:24.50 05:09:29 INFO - PROCESS | 1657 | Values: 183.0 186.0 185.0 184.0 194.0 258.0 200.0 182.0 182.0 182.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:69.20 Median:66.50 stddev:14.51 (21.8%) stddev-sans-first:14.75 05:09:29 INFO - PROCESS | 1657 | Values: 81.0 70.0 57.0 87.0 59.0 58.0 63.0 61.0 98.0 58.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:79.00 stddev:12.93 (16.4%) stddev-sans-first:13.70 05:09:29 INFO - PROCESS | 1657 | Values: 83.0 88.0 92.0 72.0 75.0 68.0 98.0 70.0 67.0 102.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:1.77 (2.0%) stddev-sans-first:1.67 05:09:29 INFO - PROCESS | 1657 | Values: 89.0 86.0 88.0 85.0 87.0 85.0 89.0 86.0 88.0 84.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:93.00 stddev:2.87 (3.1%) stddev-sans-first:2.95 05:09:29 INFO - PROCESS | 1657 | Values: 92.0 93.0 102.0 93.0 94.0 93.0 93.0 93.0 94.0 93.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.00 stddev:2.18 (2.5%) stddev-sans-first:2.18 05:09:29 INFO - PROCESS | 1657 | Values: 84.0 87.0 86.0 84.0 91.0 84.0 86.0 85.0 86.0 88.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:1.03 (1.9%) stddev-sans-first:1.00 05:09:29 INFO - PROCESS | 1657 | Values: 56.0 54.0 56.0 54.0 54.0 56.0 54.0 54.0 54.0 56.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:73.50 stddev:3.78 (5.1%) stddev-sans-first:3.16 05:09:29 INFO - PROCESS | 1657 | Values: 66.0 74.0 78.0 78.0 73.0 70.0 75.0 71.0 71.0 70.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:7.69 (9.0%) stddev-sans-first:8.13 05:09:29 INFO - PROCESS | 1657 | Values: 85.0 84.0 82.0 85.0 86.0 85.0 81.0 84.0 87.0 108.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:143.60 Median:146.50 stddev:11.45 (7.8%) stddev-sans-first:11.97 05:09:29 INFO - PROCESS | 1657 | Values: 138.0 149.0 150.0 133.0 131.0 133.0 157.0 144.0 165.0 136.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:163.50 stddev:22.93 (14.0%) stddev-sans-first:23.74 05:09:29 INFO - PROCESS | 1657 | Values: 159.0 164.0 163.0 168.0 223.0 209.0 161.0 162.0 160.0 163.0 05:09:29 INFO - PROCESS | 1657 | 05:09:29 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.94 (1.4%) stddev-sans-first:0.67 05:09:29 INFO - PROCESS | 1657 | Values: 68.0 66.0 67.0 66.0 66.0 66.0 66.0 65.0 65.0 65.0 05:09:29 INFO - PROCESS | 1657 | -------- Summary: end -------- 05:09:29 INFO - PROCESS | 1657 | 05:09:30 INFO - TEST-INFO | 1657: exit 0 05:09:30 INFO - TEST-OK | kraken | took 59281ms 05:09:30 INFO - SUITE-END | took 1176s 05:09:30 INFO - Completed test suite (00:19:36) 05:09:30 INFO - javascript benchmark 05:09:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3400.3879301725688, 3413.368526822538, 3418.8461519231623, 3438.277961628058, 3476.235228249786, 5670.461306175917, 5784.103463963082, 5818.098194779811, 5892.55805814564, 5900.88198236035, 5805.535557155428, 5852.0, 5853.5902486825835, 5862.237909071825, 5876.76492940283, 5233.162693968975, 5259.263703081571, 5291.391489978644, 5311.521963023327, 5351.892962140755, 5271.15661494161, 5323.041852466555, 5324.946750532503, 5355.892882142355, 5370.462953704618, 5284.735763211843, 5285.022270879899, 5308.362996440433, 5338.706371149585, 5356.54469370104, 5361.624686271982, 5370.7046112463795, 5380.515753582157, 5381.838544843661, 5402.270693456372, 4550.840720574764, 5074.923876141861, 5192.1952097424955, 5211.765470553834, 5300.416954135042, 5763.971180144114, 5765.884682306372, 5782.2483077199695, 5787.421257874221, 5830.82507524775, 6261.46777523911, 6262.029385445263, 6280.089387038899, 6281.937180628181, 6283.717232724538, 6159.599626024339, 6230.719617617218, 6302.684865756693, 6321.620702757803, 6326.114343991799, 5948.078047902625, 5953.940460595425, 5967.433093856077, 5972.402759723993, 5983.431574000463, 5396.4333744957, 6338.176037115146, 6349.174607301022, 6369.681515924278, 6371.936280637135, 4948.356713627206, 6097.111002522986, 6143.757528502154, 6229.96885015572, 6243.719032643542, 5722.7982123753645, 6358.809235722936, 6364.840878978062, 6365.49076073913, 6375.203099612549, 5917.911231331534, 6257.436830685169, 6260.154879091356, 6289.7484100636375, 6294.339094395023, 6125.784909797739, 6187.257529096537, 6210.285817130997, 6264.68676566179, 6302.054691796176, 5017.473165317662, 5036.79852805891, 5046.84859454217, 5078.314427552233, 5118.360204974379, 4491.011977364975, 4499.820007199741, 4518.277075667877, 4541.68208225421, 4556.202664533694, 5886.087656413263, 5894.852628684232, 5895.35151133375, 5905.822825315161, 5952.553558483131, 6165.229346331708, 6225.564210505312, 6259.311475737665, 6262.624242545462, 6300.086487459293, 5748.856278593069, 5796.710164491843, 5812.505936995318, 5833.125031245348, 5870.941290587125, 5494.780208791603, 5506.174073888948, 5508.807191748269, 5514.696691681996, 5520.475554822286, 6258.968705156445, 6300.8424789379715, 6314.305426403, 6318.399752023551, 6422.42198202164, 6351.2060992375955, 6361.904571431525, 6366.554341196257, 6373.426391624776, 6381.266154392304, 6239.438450539473, 6320.525960553069, 6398.680065996775, 6418.871622567616, 6421.261554921244, 6219.378062193744, 6237.376262373727, 6263.217097862767, 6322.430981211713, 6323.683815809283, 5921.200637913998, 6264.812055638338, 6343.55595108338, 6350.1744773181035, 6354.523410744305, 6004.399560044136, 6022.8795424090395, 6088.756449741961, 6245.156903817927, 6255.499560035278, 6055.969720151371, 6118.9846826261555, 6135.018317212141, 6139.246828488627, 6143.603994442082, 5944.3164036136395, 5953.315368732651, 5958.0, 5978.970105149447, 6005.579609427298, 6042.214512113397, 6099.8243298231955, 6135.509159267337, 6169.413905678954, 6173.32093469709, 5905.616134951386, 5960.940390596212, 5980.282366116094, 6025.156478092983, 6072.180255665428], "value": 5763.145514651867, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.45543169922894, 470.18188352267055, 516.7442116152503, 525.0889706359463, 549.2530158983782, 30200.244993875163, 31760.682393176063, 31806.681933180662, 31833.885813996487, 41669.37495937564, 39159.608403915954, 40505.58230461935, 40947.38578921311, 43584.128317433635, 43778.34332485007, 9063.13900179482, 9237.861432078524, 9963.551640176209, 9963.950180249125, 9970.90029099707, 7454.701811927516, 8983.056779038205, 9758.512074396287, 9863.161631261351, 12017.0, 11949.0, 12298.508059677646, 12304.44629991648, 12305.83094606011, 12337.198082124678, 11976.281423114597, 12044.939775301067, 12065.517379304818, 12070.577529786504, 12114.0, 8754.824903501958, 8914.955425222832, 8924.955375223148, 9087.954560227223, 11995.460204290828, 40360.79819600913, 40618.0, 40686.59313406858, 42795.0, 42841.143177136284, 3511.227529943408, 3659.688926441229, 4119.97940010301, 4155.376693495969, 4278.850240241608, 30614.693853061406, 32430.83784581062, 32529.83735081333, 32557.51163732546, 32593.837030814695, 3189.1708155879405, 3335.6831101045605, 3446.7931924084637, 3449.413599688059, 3450.7929524228375, 538061.0, 542788.2860585672, 543898.0, 544371.0, 547120.0, 63757.68121159457, 64436.677816610616, 64458.0, 64461.35538644647, 64463.0, 66355.66822165955, 66441.0, 66527.34726527309, 66549.0, 66681.33318666849, 16734.58163545921, 16989.830101699074, 17005.744913826304, 17013.149342532823, 17017.574560635836, 12971.156874803297, 13070.150440221356, 13076.476940922446, 13079.607611771662, 13102.410391532214, 16888.746668799977, 17282.567935801704, 18958.905205473886, 19407.0, 19424.8057519423, 5119.59043276537, 7730.304272615492, 7808.726694565777, 7809.648565814552, 7937.642806073741, 902.3728508686531, 960.6013504395597, 1065.760203954104, 1137.436968700482, 1145.9583883161858, 30661.540076898866, 30741.692583073884, 30809.537856932166, 31518.842405787826, 31552.737890484124, 1062.7821296634172, 1112.7996960547018, 1123.702218911989, 1164.7612239490884, 1168.7019809948408, 15870.52388428349, 15942.681146377008, 15957.521274361789, 16188.372336553111, 16457.753133703005, 718721.0, 729165.0, 729447.0, 731027.0, 731928.0, 51291.48708512942, 52020.739896301035, 58709.41290587125, 59393.812123757285, 60948.0, 1293.2693028438903, 1320.7980737528803, 1414.0, 1414.943402263939, 1419.7089596632873, 30634.234144145685, 31543.0, 31851.0, 32197.03408897737, 32521.34957300888, 50202.0, 50702.985940281724, 50729.49270507395, 50747.238791418895, 50820.74589627028, 455609.0, 471007.0, 471649.0, 472437.0, 686646.0, 26605.0, 26933.730662693906, 26947.86526067357, 26971.460570788866, 27004.594931075655, 46852.29721554111, 46892.7655361721, 47484.7625761869, 47500.52499474961, 48385.27422088742, 38698.80650596729, 38907.63823266285, 39149.0, 39538.40692389674, 39953.0, 38467.0, 38523.0, 38653.226935461695, 39546.60453395544, 39709.40435893406, 37798.0, 38456.423153652155, 38518.614813851505, 38593.0, 38722.80638596789, 30978.831481796056, 32266.0, 32574.837125814218, 34324.0, 34387.0, 24689.876550617133, 24880.37799055038, 25248.74751252464, 25354.619680705175, 25381.0], "value": 20573.57021511504, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.97621154624966, 384.3945785388014, 395.50364292812515, 396.59349167103716, 413.53270803991484, 15729.764053539191, 20309.390718278457, 21409.250676226337, 21514.569708605857, 21975.890120549415, 20810.0, 21702.782972170313, 22127.004284807183, 22145.66781498279, 22226.11095556176, 5569.913866795956, 5752.223449834271, 6015.729292181863, 6050.0, 6057.454829065383, 5984.3716409777, 6041.848953776169, 6060.727267272961, 6072.180255665495, 6092.66490343031, 5666.603337766337, 5899.085641725539, 5904.616199947011, 6010.188624535697, 6024.759009639609, 5901.704914754267, 5925.377835327315, 5939.70301484924, 5940.762369505214, 5942.494887934531, 4920.655554111214, 5174.120399532088, 5178.97410512945, 5179.171332586806, 5184.092783762826, 26659.600105998426, 26890.327741806417, 27045.188644340702, 27396.58905116425, 27442.313942151406, 2294.5066810635626, 2306.826987975892, 2339.4736184358553, 2820.7602353799953, 2991.8630920250384, 15389.222154570913, 16067.035977841368, 16387.918060409742, 16413.1793410329, 16427.425040123537, 1799.2623024559998, 1833.6241070580631, 1936.1287420660758, 1959.0694420150458, 2043.3665563675308, 81488.70323428958, 93760.0, 121551.39224303911, 122013.38993304977, 133792.0, 8869.379143460024, 11112.44437778108, 11131.944340278409, 11155.219134660658, 11270.492827822767, 13684.52104176349, 13786.379612917443, 13798.379072941541, 13806.378712957941, 13825.51610693621, 8662.653493860302, 8671.956640216758, 8732.781680457912, 8766.947966244092, 8845.0, 8870.911290887138, 8881.689140880038, 8909.331800115016, 8995.460272383678, 9025.77435564103, 5504.504594586427, 6682.331766823376, 6682.498812589075, 6689.966550167218, 6708.792417364921, 1837.7610910581539, 1873.569079111812, 1878.8778729382545, 1885.4437940807495, 1894.1003023563696, 854.9615267312986, 955.4888134847962, 983.6901376066517, 1044.3159730376615, 1058.941758203306, 19643.0, 22675.773242267365, 22964.540709185723, 23223.419414514774, 23273.301800946, 972.8054389122062, 974.5176137811828, 975.0785507695301, 982.4891056650501, 1011.1152741351318, 10177.389356638625, 10974.506147223394, 11537.365444900612, 12117.87882121185, 12167.452464639113, 602049.0, 608188.0, 615952.0, 624616.0, 624699.8765006146, 96551.55172672415, 113406.43296783463, 123277.76722232842, 128596.07105893231, 129545.0, 1008.324422636835, 1043.3114144664485, 1044.3472829481575, 1066.5413872034944, 1068.8289873620338, 19056.52358691044, 19789.901050494656, 20380.490487737927, 20383.8980805095, 20426.0, 52731.0, 53057.73471132619, 54164.45835541594, 54932.450675492735, 55085.898282034934, 55251.723741382644, 77544.0, 78175.60912195402, 78209.0, 78753.0, 8107.22981316774, 8123.0, 8138.633761480866, 8458.74623761288, 8471.449355791854, 13903.304834758424, 13908.165510069428, 13964.860351396763, 14095.436182552583, 14147.787783183054, 11541.711457213636, 11669.941650291694, 11744.70638234051, 11775.52897884075, 11788.528458861549, 11524.769504609692, 11591.362475063952, 11801.35092569917, 11801.468933898166, 12074.758504829679, 11883.16817822744, 11907.464164112462, 11912.166148369532, 12067.75864482688, 12075.51697932073, 8025.117237104031, 8030.478018928751, 8149.633266502903, 8212.548309842778, 8330.583470826556, 9871.950640246752, 11527.8270825936, 11689.941550292195, 11720.296782192754, 11761.176717630025], "value": 9985.467463938343, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1188.5364707763968, 1547.760097184936, 1548.3110016042858, 1584.548403704944, 1896.497428181532, 7761.379089672827, 8241.0, 8241.670333186665, 8242.381821363399, 8245.299149572293, 8378.204070613281, 8609.913900860989, 8610.483370997745, 8722.563871806417, 8732.214100730933, 2313.826463015276, 2378.8453750506246, 2383.7139543254834, 2384.2132096408145, 2384.523095380922, 2332.7317358503733, 2351.3063646224405, 2372.525494901018, 2373.95252094958, 2377.9881100594557, 2247.2696373678536, 2263.7396699379533, 2272.409173614864, 2283.5889539882896, 2287.279506955312, 2322.4890524084676, 2351.2593533037125, 2353.1411034972284, 2361.1027809432476, 2375.0, 2349.800266977309, 2354.117206047732, 2355.4464700795297, 2356.2342238772467, 2359.8702071386065, 8576.142385761435, 8769.0, 8771.210591046773, 8778.209961103532, 8787.912120878773, 4750.097481478508, 4920.6801557898625, 5145.434002259748, 5185.5333020028, 5319.202119682079, 3458.5503884494856, 3492.4935884296888, 3605.801680907549, 3615.385384484644, 3624.27514497099, 1003.19744204636, 1047.7694907120422, 1061.888501707325, 1096.0628662493612, 1107.778444311141, 11575.479103440366, 11699.23954942926, 11729.941350293278, 11735.7066073348, 11758.353290569015, 4651.62786977041, 4651.674382793172, 4806.831760888352, 4815.422149342101, 4823.059503396804, 10026.548805303779, 10026.64906728261, 10030.0, 10053.994600539887, 10054.396736195851, 5369.0872551665525, 5369.140937449989, 5377.0, 5392.460753924576, 5407.702576358338, 4862.635302352338, 4880.853574392774, 4928.8767780805765, 4929.75351232437, 4930.704157750546, 3787.034306251888, 3795.848166073326, 3825.0628595993603, 3889.7860617665733, 3915.7454765440157, 1052.55792567122, 1218.7867123253395, 1229.692576855786, 1232.211384713784, 1250.6748245456065, 868.5179725252419, 930.9953450232706, 944.8818897637796, 962.5187406296851, 980.3333733061587, 5732.426757324234, 5958.344582095966, 6056.788012419544, 6105.236845394325, 6205.627662340274, 432.8506665200501, 441.15738938627067, 446.76321549578773, 449.45391349510504, 456.4363011680572, 4064.227796718614, 4071.2671719090254, 4117.629413352813, 4139.482564679415, 4163.084121493267, 10244.078032976919, 10420.843687344695, 10494.580216791242, 10642.680719578426, 10642.89357106419, 10618.628348007782, 10628.309159904582, 10634.840477392845, 10635.893641063645, 10643.52104155315, 7783.4162437817395, 8103.716369927024, 8151.5924203790755, 8205.0, 8252.917470825336, 6080.361562035922, 6092.116643086639, 6197.349278325892, 6212.0, 6221.657808820377, 9984.35101718386, 9997.450140242356, 10012.899871001488, 10022.298439109192, 10025.448600327052, 9580.329376943548, 9899.752506187402, 10234.795304094025, 10333.379997200193, 10373.118284945562, 6146.815595532141, 7264.382527485223, 7528.360089392463, 7536.547807131589, 7542.4720269582795, 8183.79540511492, 8294.502329860228, 8340.29107525867, 8356.916430835614, 8374.623141958504, 7490.70037198506, 7509.324160825552, 7565.962170189114, 7635.121960974337, 7640.426967977312, 7251.057362542836, 7267.527610705287, 7304.0, 7406.666699998621, 7413.962930185315, 6980.162380514168, 7023.47323950716, 7062.223155452999, 7071.7878463646175, 7091.971664108572, 3956.564777874374, 5021.593953692972, 5023.723695196799, 5047.116754568009, 5161.174212126042, 4980.277859710394, 5021.07110184617, 5022.74886255693, 5098.566621837185, 5140.3831540215415], "value": 4484.5800018671125, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.4093737369707, 300.9924751881204, 305.3511288511912, 315.142811552577, 318.77048525061946, 4871.439784424792, 5344.4922732340465, 5470.702305031891, 5643.774249030044, 5670.546356291492, 4978.751062446872, 5317.441668624797, 5427.891442171154, 5521.861953451155, 5565.2486914266565, 4194.895127621803, 4244.851430199944, 4245.724027938174, 4252.064545799919, 4263.829446822124, 3739.5699494558066, 4008.1983603279314, 4089.977505623594, 4255.702100852942, 4278.432940235849, 3360.395128876801, 4222.218889505451, 4228.6617070634275, 4238.173556156551, 4292.849750258742, 4044.5146582410007, 4204.684648651363, 4221.0, 4229.344451610005, 4238.618524332809, 3551.4672799080085, 3682.2819550187724, 3697.186618943828, 3702.500162478058, 3730.9180337702032, 5052.494750524955, 5282.822833623071, 5401.864953376158, 5419.132938729785, 5497.47773961473, 3891.805409729502, 4042.4946881639794, 4635.629149668019, 4884.853454396374, 4927.457979622239, 4702.788374523155, 5851.882962340772, 5864.953182304239, 5865.794697185621, 5878.354740366852, 3379.678930501598, 4041.6864519031433, 4054.6553542948886, 4078.163976384834, 4100.712950093494, 4878.438979517401, 6082.482988945945, 6089.604175728564, 6091.604045737013, 6097.451229389376, 3238.8704451821663, 3871.812425979369, 3940.704447166474, 3972.6424621784176, 3983.4024896265214, 3373.4939759036342, 3862.8454861805776, 3997.200559888034, 4075.3275709508193, 4076.0, 4598.471175814757, 4714.976425117852, 4718.693284936537, 4785.372973189133, 4805.13507568641, 5076.5632496401195, 5842.072116201643, 5847.26909136358, 5858.619189752652, 5973.163757074013, 1251.7997120460682, 1308.3065975033057, 1361.9455221791216, 1384.6607581142482, 1393.5610282760897, 708.348319546019, 825.1377310710322, 829.6515463505342, 843.4306842881031, 849.2017503546647, 4248.326840475453, 4608.239640459354, 4609.331646911246, 4624.8900263937085, 4666.346711460398, 4341.176705783579, 5036.949630503721, 5069.290299358093, 5074.720890350993, 5083.949160508421, 3766.3597188478025, 4544.7500387479, 4545.5908968193025, 4563.338315944169, 4626.3860918580895, 2547.592385218356, 2696.393311504896, 2709.6071069694785, 2712.0507822262052, 2713.3623598454346, 4365.75988320639, 4773.570378665937, 4793.448753393334, 4794.544518270759, 5314.654547454403, 4948.950510494849, 4952.653314268039, 5169.663971841818, 5215.426303106655, 5255.474452554714, 4361.0, 5131.7434128293435, 5193.558547523503, 5239.266502689627, 5252.395974462909, 4316.482022157235, 5034.798608055783, 5064.632549211766, 5065.936153407825, 5103.668261562986, 4585.3951117108745, 4645.3496510487885, 4840.685355451885, 4992.126377883928, 5008.645936409882, 4536.4783049948355, 4580.908381832412, 4797.464811260364, 5154.299081303086, 5219.5041471060185, 2946.9557956630238, 3385.9830700846337, 3492.9521143657307, 3511.841967111537, 3745.0824547985917, 5031.396232452129, 5530.751116199861, 5650.547956163579, 5870.9412905870395, 6036.8188954331445, 5331.786728530816, 5526.281583394133, 5833.949889019847, 5854.590178687451, 5928.851778705567, 4720.693154944917, 5779.786244888621, 5844.12338149281, 5934.970325148347, 5961.91057134152, 4971.353724015855, 5314.4951229633125, 5774.48029677331, 5783.971080144573, 5803.504493067852, 4908.263760435963, 5868.501177399969, 5964.135200395832, 5965.70171491415, 5973.313068996948, 5039.546440820343, 5461.945380546303, 5799.971000144972, 5824.388439213991, 5862.912056319074], "value": 4012.7537811598186, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.1910117971411, 802.2539038694016, 814.7881550796791, 823.6581818545305, 859.8667206582982, 14973.679079742644, 15578.766318505232, 16237.0, 16244.512664620066, 16296.429624963128, 14732.6316842079, 14860.925695371536, 14901.478448254313, 14910.329035193443, 14913.627159321022, 547.8249155561318, 747.383408687832, 885.2431171348485, 903.286403741047, 907.8728977943093, 839.588601585225, 920.3373571028882, 920.4431319051978, 921.345844450441, 922.077922077922, 970.5098925042844, 989.3124278626358, 996.267743208741, 996.8903420623761, 997.840345544713, 762.4129420346302, 945.3666043750635, 985.4875464758286, 988.9851652225186, 991.420019288717, 727.8435136445637, 729.1723893380997, 729.3836707981746, 729.5148726097138, 734.566605702638, 14978.026428282126, 14990.925045374812, 15003.624909377244, 15020.624484387869, 15040.398384064625, 13788.586342409644, 13902.16587004783, 13978.930105349407, 14091.718165636732, 14174.0, 12896.742065158738, 12971.156874803202, 13009.34953252343, 13158.276294803782, 13412.727652921969, 4534.229181039232, 4605.953940460586, 4628.282616194495, 4636.09596128755, 4789.688670236424, 108002.0, 108916.36625450624, 117377.4131129339, 118152.0, 118301.0, 11057.0, 11189.49647265875, 11213.383263920563, 11277.210595258253, 11633.76732465346, 18294.451166465027, 22116.668249976265, 22319.330420087423, 22337.0, 22413.103475860782, 7982.920170798334, 8047.597620118971, 8088.029436467662, 8171.79570510742, 8175.754727358189, 4330.155619654201, 4438.1567502174485, 4543.727376357429, 4641.257398816173, 4695.624350051988, 3127.202563346332, 3570.357335679603, 3579.821008949594, 3581.015220814245, 3586.3544561979093, 622.6015350175891, 640.7981485831948, 650.0996120373222, 659.2089492608889, 661.206552137437, 508.6134537751262, 534.398801348483, 632.4434497642045, 655.5181941273161, 657.3032585459429, 8643.30853531716, 8644.178803013703, 8731.388802783871, 8766.342524310574, 8852.8672069919, 506.4353246130538, 547.3158551810237, 576.2221001647742, 595.9463648271676, 626.9341719119518, 4206.453161089039, 4299.312110062375, 4302.397664326997, 4345.782710864444, 4369.934450983238, 119267.40366298286, 139074.0, 147361.0, 150640.0, 151718.24140879445, 120487.0, 122177.0, 123022.0, 124458.0, 127336.72663273249, 106526.46736766267, 106815.93184068215, 108756.40544931883, 109063.99999999841, 111543.44228278806, 6827.283135270725, 8060.959695201486, 8257.79355516117, 8377.58112094405, 8481.27909127731, 55504.44495554993, 57229.0, 57330.0, 58817.82364352774, 62521.37478625155, 59267.70366148141, 89360.5531972336, 91801.54099229728, 91865.54067229888, 92694.51138420515, 10463.970375974315, 13611.0, 13652.385642645906, 13676.179429234278, 14215.715685686022, 14712.337944792296, 15049.623759405664, 15152.848471515585, 15346.616334591725, 15924.761128582848, 14015.789763153356, 14293.213873236657, 14330.928345358207, 14370.425182992563, 14424.278786060864, 14008.859911400756, 14155.575332740034, 14224.71550568962, 14346.856531434552, 14364.209968451836, 13427.218888030318, 13795.448182073007, 14553.636159096108, 14566.85433145655, 14579.635509112357, 11187.72030699239, 11216.55133794639, 11244.66266012021, 11308.321500709984, 11443.656690299304, 10557.577696892038, 10844.620438284623, 11024.944875275572, 11291.435428228391, 11326.0], "value": 7880.8856245501565, "unit": "score"}], "name": "dromaeo_css", "value": 7427.7004025033175, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 111.0, 114.0, 113.0, 112.0, 111.0, 113.0, 113.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 120.0, 191.0, 137.0, 107.0, 127.0, 106.0, 140.0, 135.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 185.0, 184.0, 194.0, 258.0, 200.0, 182.0, 182.0, 182.0], "value": 193.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 70.0, 57.0, 87.0, 59.0, 58.0, 63.0, 61.0, 98.0, 58.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 92.0, 72.0, 75.0, 68.0, 98.0, 70.0, 67.0, 102.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 85.0, 87.0, 85.0, 89.0, 86.0, 88.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 102.0, 93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 86.0, 84.0, 91.0, 84.0, 86.0, 85.0, 86.0, 88.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 54.0, 54.0, 56.0, 54.0, 54.0, 54.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 78.0, 78.0, 73.0, 70.0, 75.0, 71.0, 71.0, 70.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 82.0, 85.0, 86.0, 85.0, 81.0, 84.0, 87.0, 108.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 149.0, 150.0, 133.0, 131.0, 133.0, 157.0, 144.0, 165.0, 136.0], "value": 143.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 164.0, 163.0, 168.0, 223.0, 209.0, 161.0, 162.0, 160.0, 163.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1451.4, "alertThreshold": 2.0}]} 05:09:30 INFO - Return code: 0 05:09:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:09:30 INFO - # TBPL SUCCESS # 05:09:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:09:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:09:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:09:30 INFO - 2016-07-12 05:09:30.654 screenresolution[1661:9545] starting screenresolution argv=screenresolution get 05:09:30 INFO - 2016-07-12 05:09:30.656 screenresolution[1661:9545] Display 0: 1600x1200x32@60 05:09:30 INFO - 2016-07-12 05:09:30.662 screenresolution[1662:9548] starting screenresolution argv=screenresolution list 05:09:30 INFO - Available Modes on Display 0 05:09:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:09:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:09:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:09:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:09:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:09:30 INFO - Intel Iris: 05:09:30 INFO - Chipset Model: Intel Iris 05:09:30 INFO - Type: GPU 05:09:30 INFO - Bus: Built-In 05:09:30 INFO - VRAM (Dynamic, Max): 1536 MB 05:09:30 INFO - Vendor: Intel (0x8086) 05:09:30 INFO - Device ID: 0x0a2e 05:09:30 INFO - Revision ID: 0x0009 05:09:30 INFO - Displays: 05:09:30 INFO - Display: 05:09:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:09:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:09:30 INFO - Display Serial Number: 1600x1200 60 05:09:30 INFO - Main Display: Yes 05:09:30 INFO - Mirror: Off 05:09:30 INFO - Online: Yes 05:09:30 INFO - Rotation: Supported 05:09:30 INFO - Return code: 0 05:09:30 INFO - Running post-action listener: _resource_record_post_action 05:09:30 INFO - ##### 05:09:30 INFO - ##### Finished run-tests step (success) 05:09:30 INFO - ##### 05:09:30 INFO - Running post-run listener: _resource_record_post_run 05:09:30 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36992512; Write bytes: 704241664; Read time: 237; Write time: 1964 05:09:30 INFO - TinderboxPrint: CPU usage
53.6% 05:09:30 INFO - TinderboxPrint: I/O read bytes / time
36,992,512 / 237 05:09:30 INFO - TinderboxPrint: I/O write bytes / time
704,241,664 / 1,964 05:09:30 INFO - TinderboxPrint: CPU idle
2,248.4 (46.4%) 05:09:30 INFO - TinderboxPrint: CPU system
966.1 (19.9%) 05:09:30 INFO - TinderboxPrint: CPU user
1,631.1 (33.7%) 05:09:30 INFO - TinderboxPrint: Swap in / out
443,432,960 / 0 05:09:30 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398117376; Write bytes: 392640000; Read time: 29358; Write time: 824 05:09:30 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 34924032; Write bytes: 293906944; Read time: 217; Write time: 1099 05:09:31 INFO - Running post-run listener: _upload_blobber_files 05:09:31 INFO - Blob upload gear active. 05:09:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:09:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:09:31 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'] 05:09:31 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 05:09:31 INFO - (blobuploader) - INFO - Open directory for files ... 05:09:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:09:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:09:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:32 INFO - (blobuploader) - INFO - Done attempting. 05:09:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:09:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:09:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:33 INFO - (blobuploader) - INFO - Done attempting. 05:09:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:09:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:09:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:09:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:09:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:09:36 INFO - (blobuploader) - INFO - Done attempting. 05:09:36 INFO - (blobuploader) - INFO - Iteration through files over. 05:09:36 INFO - Return code: 0 05:09:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:09:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:09:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cb8bdf2c0aaed93437e0751ccd3d3968b460b199069a3433b886e7b026667e7245ec0256e7b344cbe6d5a24b54fbdcc39dfbe5e2a3eb1f68dbeb887703ac772", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7832b4765aabb14b4c0550dc95918104594ca20b99f1be1f924f98f5d944763402453e72dad25078ce07c4e027ffc83d29b83f3e4b32a257200aeb3ee97378dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c35e857ba9b86f161d15d14c8c2b5dd94bf0f68e397282f03c6c6c73a0e586d41fe65553c6d4b3e7253c3a1155a77cb357598156d62e71f5487f2883fe111f7"} 05:09:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:09:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:09:36 INFO - Contents: 05:09:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cb8bdf2c0aaed93437e0751ccd3d3968b460b199069a3433b886e7b026667e7245ec0256e7b344cbe6d5a24b54fbdcc39dfbe5e2a3eb1f68dbeb887703ac772", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7832b4765aabb14b4c0550dc95918104594ca20b99f1be1f924f98f5d944763402453e72dad25078ce07c4e027ffc83d29b83f3e4b32a257200aeb3ee97378dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c35e857ba9b86f161d15d14c8c2b5dd94bf0f68e397282f03c6c6c73a0e586d41fe65553c6d4b3e7253c3a1155a77cb357598156d62e71f5487f2883fe111f7"} 05:09:36 INFO - Running post-run listener: copy_logs_to_upload_dir 05:09:36 INFO - Copying logs to upload dir... 05:09:36 INFO - mkdir: /builds/slave/test/build/upload/logs 05:09:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.686037 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-12 05:09:36.334969) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 05:09:36.338142) ========= 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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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/6cb8bdf2c0aaed93437e0751ccd3d3968b460b199069a3433b886e7b026667e7245ec0256e7b344cbe6d5a24b54fbdcc39dfbe5e2a3eb1f68dbeb887703ac772", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7832b4765aabb14b4c0550dc95918104594ca20b99f1be1f924f98f5d944763402453e72dad25078ce07c4e027ffc83d29b83f3e4b32a257200aeb3ee97378dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c35e857ba9b86f161d15d14c8c2b5dd94bf0f68e397282f03c6c6c73a0e586d41fe65553c6d4b3e7253c3a1155a77cb357598156d62e71f5487f2883fe111f7"} build_url:https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011144 build_url: 'https://queue.taskcluster.net/v1/task/MrwuiLg-SmaWPM0h_DvgUA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cb8bdf2c0aaed93437e0751ccd3d3968b460b199069a3433b886e7b026667e7245ec0256e7b344cbe6d5a24b54fbdcc39dfbe5e2a3eb1f68dbeb887703ac772", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7832b4765aabb14b4c0550dc95918104594ca20b99f1be1f924f98f5d944763402453e72dad25078ce07c4e027ffc83d29b83f3e4b32a257200aeb3ee97378dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6c35e857ba9b86f161d15d14c8c2b5dd94bf0f68e397282f03c6c6c73a0e586d41fe65553c6d4b3e7253c3a1155a77cb357598156d62e71f5487f2883fe111f7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 05:09:36.370753) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:09:36.371064) ========= 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.eKcD9KQlqe/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.I4rd5L0gs4/Listeners TMPDIR=/var/folders/st/6ntnsvx13cz7v7n60qs4hh6000000w/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.005040 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 05:09:36.419426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 05:09:36.419732) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 05:09:36.420108) ========= ========= Total master_lag: 0.17 =========