builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0139 starttime: 1468843892.13 results: success (0) buildid: 20160718020653 builduid: 13b19edfb0bf4560b66df5a5bad36c1f revision: bc547f508f58108db8b9ad9c2b94f130c07de194 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.125977) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.126337) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.126667) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.157635) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.157886) ========= 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.4zmrp6oKGE/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.biZ3s2McEW/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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-18 05:11:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.7M=0.001s 2016-07-18 05:11:32 (15.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.164042 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.345283) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.345555) ========= 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.4zmrp6oKGE/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.biZ3s2McEW/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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.027066 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.393337) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.393628) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev bc547f508f58108db8b9ad9c2b94f130c07de194 --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 bc547f508f58108db8b9ad9c2b94f130c07de194 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4zmrp6oKGE/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.biZ3s2McEW/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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-18 05:11:32,469 truncating revision to first 12 chars 2016-07-18 05:11:32,469 Setting DEBUG logging. 2016-07-18 05:11:32,469 attempt 1/10 2016-07-18 05:11:32,469 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bc547f508f58?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 05:11:32,770 unpacking tar archive at: fx-team-bc547f508f58/testing/mozharness/ program finished with exit code 0 elapsedTime=0.576552 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.991031) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.991344) ========= script_repo_revision: bc547f508f58108db8b9ad9c2b94f130c07de194 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.991716) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:32.991981) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 05:11:33.009426) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-18 05:11:33.009691) ========= /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.4zmrp6oKGE/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.biZ3s2McEW/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:11:33 INFO - MultiFileLogger online at 20160718 05:11:33 in /builds/slave/test 05:11:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:11:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:11:33 INFO - {'append_to_log': False, 05:11:33 INFO - 'base_work_dir': '/builds/slave/test', 05:11:33 INFO - 'blob_upload_branch': 'Fx-Team', 05:11:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:11:33 INFO - 'branch': 'Fx-Team', 05:11:33 INFO - 'buildbot_json_path': 'buildprops.json', 05:11:33 INFO - 'config_files': ('talos/mac_config.py',), 05:11:33 INFO - 'default_actions': ('clobber', 05:11:33 INFO - 'read-buildbot-config', 05:11:33 INFO - 'download-and-extract', 05:11:33 INFO - 'populate-webroot', 05:11:33 INFO - 'create-virtualenv', 05:11:33 INFO - 'install', 05:11:33 INFO - 'run-tests'), 05:11:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:11:33 INFO - 'download_minidump_stackwalk': True, 05:11:33 INFO - 'download_symbols': 'ondemand', 05:11:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:11:33 INFO - 'tooltool.py': '/tools/tooltool.py', 05:11:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:11:33 INFO - '/tools/misc-python/virtualenv.py')}, 05:11:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:11:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:11:33 INFO - 'installer_path': 'installer.exe', 05:11:33 INFO - 'log_level': 'info', 05:11:33 INFO - 'log_name': 'talos', 05:11:33 INFO - 'log_to_console': True, 05:11:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:11:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:11:33 INFO - 'opt_config_files': (), 05:11:33 INFO - 'pip_index': False, 05:11:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:11:33 INFO - 'cmd': ('bash', 05:11:33 INFO - '-c', 05:11:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:11:33 INFO - 'enabled': True, 05:11:33 INFO - 'halt_on_failure': False, 05:11:33 INFO - 'name': 'check_screen_resolution'},), 05:11:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:11:33 INFO - 'cmd': ('bash', 05:11:33 INFO - '-c', 05:11:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:11:33 INFO - 'enabled': True, 05:11:33 INFO - 'halt_on_failure': False, 05:11:33 INFO - 'name': 'check_screen_resolution'},), 05:11:33 INFO - 'run_cmd_checks_enabled': True, 05:11:33 INFO - 'sps_profile': False, 05:11:33 INFO - 'sps_profile_interval': 0, 05:11:33 INFO - 'suite': 'dromaeojs', 05:11:33 INFO - 'system_bits': '32', 05:11:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:11:33 INFO - 'title': 't-yosemite-r7-0139', 05:11:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:11:33 INFO - 'use_talos_json': True, 05:11:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:11:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:11:33 INFO - 'work_dir': 'build'} 05:11:33 INFO - ##### 05:11:33 INFO - ##### Running clobber step. 05:11:33 INFO - ##### 05:11:33 INFO - Running pre-action listener: _resource_record_pre_action 05:11:33 INFO - Running main action method: clobber 05:11:33 INFO - rmtree: /builds/slave/test/build 05:11:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:11:35 INFO - Running post-action listener: _resource_record_post_action 05:11:35 INFO - ##### 05:11:35 INFO - ##### Finished clobber step (success) 05:11:35 INFO - ##### 05:11:35 INFO - ##### 05:11:35 INFO - ##### Running read-buildbot-config step. 05:11:35 INFO - ##### 05:11:35 INFO - Running pre-action listener: _resource_record_pre_action 05:11:35 INFO - Running main action method: read_buildbot_config 05:11:35 INFO - Using buildbot properties: 05:11:35 INFO - { 05:11:35 INFO - "project": "", 05:11:35 INFO - "product": "firefox", 05:11:35 INFO - "script_repo_revision": "production", 05:11:35 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:11:35 INFO - "repository": "", 05:11:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:11:35 INFO - "buildid": "20160718020653", 05:11:35 INFO - "pgo_build": "False", 05:11:35 INFO - "basedir": "/builds/slave/test", 05:11:35 INFO - "buildnumber": 111, 05:11:35 INFO - "slavename": "t-yosemite-r7-0139", 05:11:35 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:11:35 INFO - "platform": "yosemite_r7", 05:11:35 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:11:35 INFO - "branch": "fx-team", 05:11:35 INFO - "repo_path": "integration/fx-team", 05:11:35 INFO - "revision": "bc547f508f58108db8b9ad9c2b94f130c07de194", 05:11:35 INFO - "stage_platform": "macosx64", 05:11:35 INFO - "builduid": "13b19edfb0bf4560b66df5a5bad36c1f", 05:11:35 INFO - "slavebuilddir": "test" 05:11:35 INFO - } 05:11:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:11:35 INFO - Running post-action listener: _resource_record_post_action 05:11:35 INFO - ##### 05:11:35 INFO - ##### Finished read-buildbot-config step (success) 05:11:35 INFO - ##### 05:11:35 INFO - ##### 05:11:35 INFO - ##### Running download-and-extract step. 05:11:35 INFO - ##### 05:11:35 INFO - Running pre-action listener: _resource_record_pre_action 05:11:35 INFO - Running main action method: download_and_extract 05:11:35 INFO - mkdir: /builds/slave/test/build/tests 05:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:35 INFO - https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:11:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:11:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:11:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/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 05:11:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/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 05:11:36 INFO - Downloaded 1199 bytes. 05:11:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:11:36 INFO - Using the following test package requirements: 05:11:36 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:11:36 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:11:36 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:11:36 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:11:36 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:11:36 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:11:36 INFO - u'jsshell-mac.zip'], 05:11:36 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:11:36 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:11:36 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:11:36 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:11:36 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:11:36 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:11:36 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:11:36 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:11:36 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:11:36 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:11:36 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:11:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:36 INFO - https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:11:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:11:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:11:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/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 05:11:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/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 05:11:37 INFO - Downloaded 18835870 bytes. 05:11:37 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'] 05:11:37 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 05:11:40 INFO - Return code: 0 05:11:40 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 05:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:40 INFO - https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:11:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:11:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:11:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/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 05:11:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/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 05:11:41 INFO - Downloaded 18835870 bytes. 05:11:41 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'] 05:11:41 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 05:11:46 INFO - Return code: 0 05:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:46 INFO - https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:11:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:11:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:11:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/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 05:11:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/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 05:11:47 INFO - Downloaded 14403317 bytes. 05:11:47 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'] 05:11:47 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 05:11:47 INFO - Return code: 0 05:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:47 INFO - https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:11:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:11:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:11:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:11:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CR_HfFEcQMm2JenIb_MYgA/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 05:11:51 INFO - Downloaded 112758432 bytes. 05:11:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:11:51 INFO - mkdir: /builds/slave/test/properties 05:11:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:11:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:11:51 INFO - Contents: 05:11:51 INFO - build_url:https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:11:52 INFO - Running post-action listener: _resource_record_post_action 05:11:52 INFO - Running post-action listener: set_extra_try_arguments 05:11:52 INFO - ##### 05:11:52 INFO - ##### Finished download-and-extract step (success) 05:11:52 INFO - ##### 05:11:52 INFO - ##### 05:11:52 INFO - ##### Running populate-webroot step. 05:11:52 INFO - ##### 05:11:52 INFO - Running pre-action listener: _resource_record_pre_action 05:11:52 INFO - Running main action method: populate_webroot 05:11:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:11:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:11:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:11:53 INFO - '--tppagecycles', 05:11:53 INFO - '7'], 05:11:53 INFO - 'tests': ['tsvgm']}}, 05:11:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:11:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:11:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:11:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:11:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:53 INFO - 'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:53 INFO - 'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['damp', 'tps']}, 05:11:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:53 INFO - 'tests': ['damp', 'tps']}, 05:11:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['dromaeo_dom']}, 05:11:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:11:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['basic_compositor_video']}, 05:11:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:11:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['a11yr', 05:11:53 INFO - 'ts_paint', 05:11:53 INFO - 'tpaint', 05:11:53 INFO - 'sessionrestore', 05:11:53 INFO - 'sessionrestore_no_auto_restore', 05:11:53 INFO - 'tabpaint']}, 05:11:53 INFO - 'other-e10s': {'tests': ['a11yr', 05:11:53 INFO - 'ts_paint', 05:11:53 INFO - 'tpaint', 05:11:53 INFO - 'sessionrestore', 05:11:53 INFO - 'sessionrestore_no_auto_restore', 05:11:53 INFO - 'tabpaint']}, 05:11:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:11:53 INFO - 'ts_paint', 05:11:53 INFO - 'tpaint', 05:11:53 INFO - 'sessionrestore', 05:11:53 INFO - 'sessionrestore_no_auto_restore', 05:11:53 INFO - 'tabpaint']}, 05:11:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:11:53 INFO - 'ts_paint', 05:11:53 INFO - 'tpaint', 05:11:53 INFO - 'sessionrestore', 05:11:53 INFO - 'sessionrestore_no_auto_restore', 05:11:53 INFO - 'tabpaint']}, 05:11:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['a11yr', 05:11:53 INFO - 'ts_paint', 05:11:53 INFO - 'tpaint', 05:11:53 INFO - 'sessionrestore', 05:11:53 INFO - 'sessionrestore_no_auto_restore', 05:11:53 INFO - 'tabpaint']}, 05:11:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['a11yr', 05:11:53 INFO - 'ts_paint', 05:11:53 INFO - 'tpaint', 05:11:53 INFO - 'sessionrestore', 05:11:53 INFO - 'sessionrestore_no_auto_restore', 05:11:53 INFO - 'tabpaint']}, 05:11:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['tsvgx', 05:11:53 INFO - 'tsvgr_opacity', 05:11:53 INFO - 'tart', 05:11:53 INFO - 'tscrollx', 05:11:53 INFO - 'cart']}, 05:11:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:11:53 INFO - 'tsvgr_opacity', 05:11:53 INFO - 'tart', 05:11:53 INFO - 'tscrollx', 05:11:53 INFO - 'cart']}, 05:11:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:53 INFO - 'talos_options': ['--disable-e10s'], 05:11:53 INFO - 'tests': ['tp5o']}, 05:11:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:53 INFO - 'tests': ['tp5o']}, 05:11:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:53 INFO - 'talos_options': ['--disable-e10s', 05:11:53 INFO - '--xperf_path', 05:11:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:53 INFO - 'tests': ['tp5n']}, 05:11:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:53 INFO - 'talos_options': ['--xperf_path', 05:11:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:53 INFO - 'tests': ['tp5n']}}, 05:11:53 INFO - 'talos.zip': {'path': '', 05:11:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:11:53 INFO - Running post-action listener: _resource_record_post_action 05:11:53 INFO - ##### 05:11:53 INFO - ##### Finished populate-webroot step (success) 05:11:53 INFO - ##### 05:11:53 INFO - ##### 05:11:53 INFO - ##### Running create-virtualenv step. 05:11:53 INFO - ##### 05:11:53 INFO - Running pre-action listener: _resource_record_pre_action 05:11:53 INFO - Running main action method: create_virtualenv 05:11:53 INFO - Creating virtualenv /builds/slave/test/build/venv 05:11:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:11:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:11:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:11:53 INFO - Using real prefix '/tools/python27' 05:11:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:11:53 INFO - Installing distribute.............................................................................................................................................................................................done. 05:11:56 INFO - Installing pip.................done. 05:11:56 INFO - Return code: 0 05:11:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eee1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2468b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, '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.4zmrp6oKGE/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.biZ3s2McEW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:11:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:11:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:56 INFO - 'HOME': '/Users/cltbld', 05:11:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:56 INFO - 'LOGNAME': 'cltbld', 05:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:56 INFO - 'MOZ_NO_REMOTE': '1', 05:11:56 INFO - 'NO_EM_RESTART': '1', 05:11:56 INFO - 'PAGER': '/bin/cat', 05:11:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:56 INFO - 'PWD': '/builds/slave/test', 05:11:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:56 INFO - 'SHELL': '/bin/bash', 05:11:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:11:56 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:11:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:56 INFO - 'USER': 'cltbld', 05:11:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:56 INFO - 'XPC_FLAGS': '0x0', 05:11:56 INFO - 'XPC_SERVICE_NAME': '0', 05:11:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:11:56 INFO - Cleaning up... 05:11:56 INFO - Return code: 0 05:11:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eee1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2468b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, '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.4zmrp6oKGE/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.biZ3s2McEW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:11:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:11:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:56 INFO - 'HOME': '/Users/cltbld', 05:11:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:56 INFO - 'LOGNAME': 'cltbld', 05:11:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:56 INFO - 'MOZ_NO_REMOTE': '1', 05:11:56 INFO - 'NO_EM_RESTART': '1', 05:11:56 INFO - 'PAGER': '/bin/cat', 05:11:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:56 INFO - 'PWD': '/builds/slave/test', 05:11:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:56 INFO - 'SHELL': '/bin/bash', 05:11:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:11:56 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:11:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:56 INFO - 'USER': 'cltbld', 05:11:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:56 INFO - 'XPC_FLAGS': '0x0', 05:11:56 INFO - 'XPC_SERVICE_NAME': '0', 05:11:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:57 INFO - Downloading/unpacking psutil>=3.1.1 05:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:12:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:00 INFO - Installing collected packages: psutil 05:12:00 INFO - Running setup.py install for psutil 05:12:00 INFO - building 'psutil._psutil_osx' extension 05:12:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:12:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:12:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:12:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:12:01 INFO - building 'psutil._psutil_posix' extension 05:12:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:12:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:12:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:12:01 INFO - ^ 05:12:01 INFO - 1 warning generated. 05:12:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:12:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:01 INFO - Successfully installed psutil 05:12:01 INFO - Cleaning up... 05:12:01 INFO - Return code: 0 05:12:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:01 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 0x10ee4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eee1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2468b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, '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.4zmrp6oKGE/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.biZ3s2McEW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:12:01 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 05:12:01 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 05:12:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:12:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:01 INFO - 'HOME': '/Users/cltbld', 05:12:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:01 INFO - 'LOGNAME': 'cltbld', 05:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:01 INFO - 'MOZ_NO_REMOTE': '1', 05:12:01 INFO - 'NO_EM_RESTART': '1', 05:12:01 INFO - 'PAGER': '/bin/cat', 05:12:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:01 INFO - 'PWD': '/builds/slave/test', 05:12:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:01 INFO - 'SHELL': '/bin/bash', 05:12:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:12:01 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:12:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:01 INFO - 'USER': 'cltbld', 05:12:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:01 INFO - 'XPC_FLAGS': '0x0', 05:12:01 INFO - 'XPC_SERVICE_NAME': '0', 05:12:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:12:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:12:04 INFO - Installing collected packages: mozsystemmonitor 05:12:04 INFO - Running setup.py install for mozsystemmonitor 05:12:04 INFO - Successfully installed mozsystemmonitor 05:12:04 INFO - Cleaning up... 05:12:04 INFO - Return code: 0 05:12:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eee1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2468b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, '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.4zmrp6oKGE/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.biZ3s2McEW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:12:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:12:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:04 INFO - 'HOME': '/Users/cltbld', 05:12:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:04 INFO - 'LOGNAME': 'cltbld', 05:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:04 INFO - 'MOZ_NO_REMOTE': '1', 05:12:04 INFO - 'NO_EM_RESTART': '1', 05:12:04 INFO - 'PAGER': '/bin/cat', 05:12:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:04 INFO - 'PWD': '/builds/slave/test', 05:12:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:04 INFO - 'SHELL': '/bin/bash', 05:12:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:12:04 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:12:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:04 INFO - 'USER': 'cltbld', 05:12:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:04 INFO - 'XPC_FLAGS': '0x0', 05:12:04 INFO - 'XPC_SERVICE_NAME': '0', 05:12:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:04 INFO - Downloading/unpacking jsonschema==2.5.1 05:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:12:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:07 INFO - Downloading functools32-3.2.3-2.tar.gz 05:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:12:07 INFO - warning: no files found matching '*.txt' 05:12:07 INFO - no previously-included directories found matching 'build' 05:12:07 INFO - no previously-included directories found matching 'dist' 05:12:07 INFO - no previously-included directories found matching '.git*' 05:12:07 INFO - Installing collected packages: jsonschema, functools32 05:12:07 INFO - Running setup.py install for functools32 05:12:07 INFO - warning: no files found matching '*.txt' 05:12:07 INFO - no previously-included directories found matching 'build' 05:12:07 INFO - no previously-included directories found matching 'dist' 05:12:07 INFO - no previously-included directories found matching '.git*' 05:12:08 INFO - Successfully installed jsonschema functools32 05:12:08 INFO - Cleaning up... 05:12:08 INFO - Return code: 0 05:12:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:08 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 0x10ee4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eee1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2468b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, '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.4zmrp6oKGE/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.biZ3s2McEW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:12:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:12:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:12:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:08 INFO - 'HOME': '/Users/cltbld', 05:12:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:08 INFO - 'LOGNAME': 'cltbld', 05:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:08 INFO - 'MOZ_NO_REMOTE': '1', 05:12:08 INFO - 'NO_EM_RESTART': '1', 05:12:08 INFO - 'PAGER': '/bin/cat', 05:12:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:08 INFO - 'PWD': '/builds/slave/test', 05:12:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:08 INFO - 'SHELL': '/bin/bash', 05:12:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:12:08 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:12:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:08 INFO - 'USER': 'cltbld', 05:12:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:08 INFO - 'XPC_FLAGS': '0x0', 05:12:08 INFO - 'XPC_SERVICE_NAME': '0', 05:12:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:10 INFO - Downloading blobuploader-1.2.4.tar.gz 05:12:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:12:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:12:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:11 INFO - Downloading docopt-0.6.1.tar.gz 05:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:12:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:12:12 INFO - Running setup.py install for blobuploader 05:12:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:12:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:12:12 INFO - Running setup.py install for requests 05:12:12 INFO - Running setup.py install for docopt 05:12:12 INFO - Successfully installed blobuploader requests docopt 05:12:12 INFO - Cleaning up... 05:12:12 INFO - Return code: 0 05:12:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:12 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 0x10ee4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eee1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2468b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, '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.4zmrp6oKGE/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.biZ3s2McEW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:12 INFO - 'HOME': '/Users/cltbld', 05:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:12 INFO - 'LOGNAME': 'cltbld', 05:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:12 INFO - 'MOZ_NO_REMOTE': '1', 05:12:12 INFO - 'NO_EM_RESTART': '1', 05:12:12 INFO - 'PAGER': '/bin/cat', 05:12:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:12 INFO - 'PWD': '/builds/slave/test', 05:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:12 INFO - 'SHELL': '/bin/bash', 05:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:12:12 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:12 INFO - 'USER': 'cltbld', 05:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:12 INFO - 'XPC_FLAGS': '0x0', 05:12:12 INFO - 'XPC_SERVICE_NAME': '0', 05:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:13 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-d2Oy8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:13 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-gHsZxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:13 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-t8ZyN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:13 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-Ews4Kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:13 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-s0OKEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:13 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-YeKlrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:13 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-T0ZVHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:13 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-Ht3Zlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:13 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-plyBTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:14 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-zd7TaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:14 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-wZMUQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:14 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-7pdwDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:14 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-VaNZV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:14 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-tZpsx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:14 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-Kyr_e9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:14 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-1C4tpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:14 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-U6LeiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:12:14 INFO - Running setup.py install for manifestparser 05:12:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:12:15 INFO - Running setup.py install for mozcrash 05:12:15 INFO - Running setup.py install for mozdebug 05:12:15 INFO - Running setup.py install for mozdevice 05:12:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:12:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:12:15 INFO - Running setup.py install for mozfile 05:12:15 INFO - Running setup.py install for mozhttpd 05:12:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:12:15 INFO - Running setup.py install for mozinfo 05:12:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:12:15 INFO - Running setup.py install for mozInstall 05:12:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:12:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:12:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:12:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:12:16 INFO - Running setup.py install for mozleak 05:12:16 INFO - Running setup.py install for mozlog 05:12:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:12:16 INFO - Running setup.py install for moznetwork 05:12:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:12:16 INFO - Running setup.py install for mozprocess 05:12:16 INFO - Running setup.py install for mozprofile 05:12:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:12:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:12:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:12:16 INFO - Running setup.py install for mozrunner 05:12:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:12:17 INFO - Running setup.py install for mozscreenshot 05:12:17 INFO - Running setup.py install for moztest 05:12:17 INFO - Running setup.py install for mozversion 05:12:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:12:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:12:17 INFO - Cleaning up... 05:12:17 INFO - Return code: 0 05:12:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:17 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 0x10ee4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eee1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2468b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, '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.4zmrp6oKGE/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.biZ3s2McEW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:12:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:12:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:12:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:17 INFO - 'HOME': '/Users/cltbld', 05:12:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:17 INFO - 'LOGNAME': 'cltbld', 05:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:17 INFO - 'MOZ_NO_REMOTE': '1', 05:12:17 INFO - 'NO_EM_RESTART': '1', 05:12:17 INFO - 'PAGER': '/bin/cat', 05:12:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:17 INFO - 'PWD': '/builds/slave/test', 05:12:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:17 INFO - 'SHELL': '/bin/bash', 05:12:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:12:17 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:12:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:17 INFO - 'USER': 'cltbld', 05:12:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:17 INFO - 'XPC_FLAGS': '0x0', 05:12:17 INFO - 'XPC_SERVICE_NAME': '0', 05:12:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:17 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-scbIlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:17 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-9wM_hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-RzqCig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-f1IZV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-GIS5_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-uuicd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-siCe59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-Rqavoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-0vFt2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-0xkm55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12:18 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)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-uoiSWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:18 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-HcqWFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12:19 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)) 05:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:19 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-vkIdxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:19 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-L9urfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:19 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)) 05:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:19 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-Dzm4Zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:19 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-R5mbRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:19 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-jkvpHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:12:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:22 INFO - Downloading blessings-1.6.tar.gz 05:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:12:22 INFO - Installing collected packages: blessings 05:12:22 INFO - Running setup.py install for blessings 05:12:22 INFO - Successfully installed blessings 05:12:22 INFO - Cleaning up... 05:12:22 INFO - Return code: 0 05:12:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:12:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:12:22 INFO - Reading from file tmpfile_stdout 05:12:22 INFO - Current package versions: 05:12:22 INFO - blessings == 1.6 05:12:22 INFO - blobuploader == 1.2.4 05:12:22 INFO - docopt == 0.6.1 05:12:22 INFO - functools32 == 3.2.3-2 05:12:22 INFO - jsonschema == 2.5.1 05:12:22 INFO - manifestparser == 1.1 05:12:22 INFO - mozInstall == 1.12 05:12:22 INFO - mozcrash == 0.17 05:12:22 INFO - mozdebug == 0.1 05:12:22 INFO - mozdevice == 0.48 05:12:22 INFO - mozfile == 1.2 05:12:22 INFO - mozhttpd == 0.7 05:12:22 INFO - mozinfo == 0.9 05:12:22 INFO - mozleak == 0.1 05:12:22 INFO - mozlog == 3.2 05:12:22 INFO - moznetwork == 0.27 05:12:22 INFO - mozprocess == 0.23 05:12:22 INFO - mozprofile == 0.28 05:12:22 INFO - mozrunner == 6.12 05:12:22 INFO - mozscreenshot == 0.1 05:12:22 INFO - mozsystemmonitor == 0.3 05:12:22 INFO - moztest == 0.7 05:12:22 INFO - mozversion == 1.4 05:12:22 INFO - psutil == 3.1.1 05:12:22 INFO - requests == 1.2.3 05:12:22 INFO - wsgiref == 0.1.2 05:12:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:22 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 0x10ee4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eee1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2468b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, '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.4zmrp6oKGE/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.biZ3s2McEW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:12:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:22 INFO - 'HOME': '/Users/cltbld', 05:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:22 INFO - 'LOGNAME': 'cltbld', 05:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:22 INFO - 'MOZ_NO_REMOTE': '1', 05:12:22 INFO - 'NO_EM_RESTART': '1', 05:12:22 INFO - 'PAGER': '/bin/cat', 05:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:22 INFO - 'PWD': '/builds/slave/test', 05:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:22 INFO - 'SHELL': '/bin/bash', 05:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:12:22 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:22 INFO - 'USER': 'cltbld', 05:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:22 INFO - 'XPC_FLAGS': '0x0', 05:12:22 INFO - 'XPC_SERVICE_NAME': '0', 05:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:12:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:12:22 INFO - Cleaning up... 05:12:22 INFO - Return code: 0 05:12:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eee1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaf2468b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, '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.4zmrp6oKGE/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.biZ3s2McEW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:12:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:22 INFO - 'HOME': '/Users/cltbld', 05:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:22 INFO - 'LOGNAME': 'cltbld', 05:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:22 INFO - 'MOZ_NO_REMOTE': '1', 05:12:22 INFO - 'NO_EM_RESTART': '1', 05:12:22 INFO - 'PAGER': '/bin/cat', 05:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:22 INFO - 'PWD': '/builds/slave/test', 05:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:22 INFO - 'SHELL': '/bin/bash', 05:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:12:22 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:22 INFO - 'USER': 'cltbld', 05:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:22 INFO - 'XPC_FLAGS': '0x0', 05:12:22 INFO - 'XPC_SERVICE_NAME': '0', 05:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:12:23 INFO - Cleaning up... 05:12:23 INFO - Return code: 0 05:12:23 INFO - Running post-action listener: _resource_record_post_action 05:12:23 INFO - Running post-action listener: _start_resource_monitoring 05:12:23 INFO - Starting resource monitoring. 05:12:23 INFO - ##### 05:12:23 INFO - ##### Finished create-virtualenv step (success) 05:12:23 INFO - ##### 05:12:23 INFO - ##### 05:12:23 INFO - ##### Running install step. 05:12:23 INFO - ##### 05:12:23 INFO - Running pre-action listener: _resource_record_pre_action 05:12:23 INFO - Running main action method: install 05:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:12:23 INFO - Reading from file tmpfile_stdout 05:12:23 INFO - Detecting whether we're running mozinstall >=1.0... 05:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:12:23 INFO - Reading from file tmpfile_stdout 05:12:23 INFO - Output received: 05:12:23 INFO - Usage: mozinstall [options] installer 05:12:23 INFO - Options: 05:12:23 INFO - -h, --help show this help message and exit 05:12:23 INFO - -d DEST, --destination=DEST 05:12:23 INFO - Directory to install application into. [default: 05:12:23 INFO - "/builds/slave/test"] 05:12:23 INFO - --app=APP Application being installed. [default: firefox] 05:12:23 INFO - mkdir: /builds/slave/test/build/application 05:12:23 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'] 05:12:23 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 05:12:58 INFO - Reading from file tmpfile_stdout 05:12:58 INFO - Output received: 05:12:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:12:58 INFO - Running post-action listener: _resource_record_post_action 05:12:58 INFO - ##### 05:12:58 INFO - ##### Finished install step (success) 05:12:58 INFO - ##### 05:12:58 INFO - ##### 05:12:58 INFO - ##### Running run-tests step. 05:12:58 INFO - ##### 05:12:58 INFO - Running pre-action listener: _resource_record_pre_action 05:12:58 INFO - Running main action method: run_tests 05:12:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:12:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:12:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:12:58 INFO - 2016-07-18 05:12:58.161 screenresolution[1636:7664] starting screenresolution argv=screenresolution get 05:12:58 INFO - 2016-07-18 05:12:58.164 screenresolution[1636:7664] Display 0: 1600x1200x32@60 05:12:58 INFO - 2016-07-18 05:12:58.169 screenresolution[1637:7666] starting screenresolution argv=screenresolution list 05:12:58 INFO - Available Modes on Display 0 05:12:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:12:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:12:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:12:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:12:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:12:58 INFO - Intel Iris: 05:12:58 INFO - Chipset Model: Intel Iris 05:12:58 INFO - Type: GPU 05:12:58 INFO - Bus: Built-In 05:12:58 INFO - VRAM (Dynamic, Max): 1536 MB 05:12:58 INFO - Vendor: Intel (0x8086) 05:12:58 INFO - Device ID: 0x0a2e 05:12:58 INFO - Revision ID: 0x0009 05:12:58 INFO - Displays: 05:12:58 INFO - Display: 05:12:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:12:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:12:58 INFO - Display Serial Number: 1600x1200 60 05:12:58 INFO - Main Display: Yes 05:12:58 INFO - Mirror: Off 05:12:58 INFO - Online: Yes 05:12:58 INFO - Rotation: Supported 05:12:58 INFO - Return code: 0 05:12:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:12:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:12:58 INFO - Python 2.7.3 05:12:58 INFO - Return code: 0 05:12:58 INFO - grabbing minidump binary from tooltool 05:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:58 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 0x10edb0d30>, '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 0x10eb15db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eda8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:12:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:12:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:12:58 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 05:12:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:12:58 INFO - Return code: 0 05:12:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:12:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:12:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:12:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:12:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:12:58 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/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0139', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:12:58 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/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0139 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4zmrp6oKGE/Render', 05:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:58 INFO - 'HOME': '/Users/cltbld', 05:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:58 INFO - 'LOGNAME': 'cltbld', 05:12:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:12:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:58 INFO - 'MOZ_NO_REMOTE': '1', 05:12:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:12:58 INFO - 'NO_EM_RESTART': '1', 05:12:58 INFO - 'PAGER': '/bin/cat', 05:12:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:58 INFO - 'PWD': '/builds/slave/test', 05:12:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:58 INFO - 'SHELL': '/bin/bash', 05:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.biZ3s2McEW/Listeners', 05:12:58 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 05:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:58 INFO - 'USER': 'cltbld', 05:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:58 INFO - 'XPC_FLAGS': '0x0', 05:12:58 INFO - 'XPC_SERVICE_NAME': '0', 05:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:58 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/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0139', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:12:58 INFO - mozversion application_buildid: 20160718020653 05:12:58 INFO - mozversion application_changeset: bc547f508f58108db8b9ad9c2b94f130c07de194 05:12:58 INFO - mozversion application_display_name: Nightly 05:12:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:12:58 INFO - mozversion application_name: Firefox 05:12:58 INFO - mozversion application_remotingname: firefox 05:12:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:12:58 INFO - mozversion application_vendor: Mozilla 05:12:58 INFO - mozversion application_version: 50.0a1 05:12:58 INFO - mozversion platform_buildid: 20160718020653 05:12:58 INFO - mozversion platform_changeset: bc547f508f58108db8b9ad9c2b94f130c07de194 05:12:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:12:58 INFO - mozversion platform_version: 50.0a1 05:12:58 INFO - using testdate: 1468843978 05:12:58 INFO - actual date: 1468843978 05:12:58 INFO - starting webserver on 'localhost:49271' 05:12:58 INFO - SUITE-START | Running 2 tests 05:12:58 INFO - TEST-START | dromaeo_css 05:12:58 INFO - operating with platform_type : mac_ 05:12:58 INFO - Initialising browser for dromaeo_css test... 05:12:58 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/tmp8G1XHD/profile http://localhost:49271/getInfo.html) 05:13:01 INFO - PROCESS | 1648 | JavaScript error: , line 0: uncaught exception: undefined 05:13:01 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 05:13:01 INFO - PROCESS | 1648 | colorDepth:24 05:13:01 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 05:13:01 INFO - PROCESS | 1648 | __metrics 05:13:01 INFO - PROCESS | 1648 | 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:13:01 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:13:02 INFO - TEST-INFO | 1648: exit 0 05:13:02 INFO - Browser initialized. 05:13:02 INFO - Running cycle 1/1 for dromaeo_css test... 05:13:02 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/tmp8G1XHD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:14 INFO - PROCESS | 1650 | RSS: Main: 245149696 05:13:14 INFO - PROCESS | 1650 | 05:14:05 INFO - PROCESS | 1650 | 1468844045874 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:16:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 05:16:17 INFO - PROCESS | 1650 | RSS: Main: 383479808 05:16:17 INFO - PROCESS | 1650 | 05:19:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 05:19:21 INFO - PROCESS | 1650 | RSS: Main: 381689856 05:19:21 INFO - PROCESS | 1650 | 05:22:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 05:22:24 INFO - PROCESS | 1650 | RSS: Main: 371720192 05:22:24 INFO - PROCESS | 1650 | 05:25:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:25:27 INFO - PROCESS | 1650 | RSS: Main: 368627712 05:25:27 INFO - PROCESS | 1650 | 05:28:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:28:31 INFO - PROCESS | 1650 | RSS: Main: 335872000 05:28:31 INFO - PROCESS | 1650 | 05:31:34 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:31:34 INFO - PROCESS | 1650 | RSS: Main: 437882880 05:31:34 INFO - PROCESS | 1650 | 05:31:34 INFO - PROCESS | 1650 | __start_tp_report 05:31:34 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 05:31:34 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 05:31:34 INFO - PROCESS | 1650 | |i|pagename|runs| 05:31:34 INFO - PROCESS | 1650 | |0;dojo.html;3383.4755612880003;3399.9830000850006;3410.727141828653;3434.347473979942;3481.56480439945;5569;5637.91543126853;5691.971540142294;5751.741171647275;5760.1359796030565;5607.691576963265;5641.971790141043;5695.8576035599135;5726.255586773714;5784.768609255635;5133.743312834344;5145.459726728695;5186.19613959835;5191.428942816287;5332.040232758102;5333.280007199017;5356.91964620531;5374.086405311087;5379.919301210485;5414.10667239905;5154.252633368178;5168;5201.479852014806;5215.817446389358;5269.604779641523;5191;5222.869428264324;5227.634065615371;5229.189475631283;5302.628815982883;5194.480551944813;5209.322788037531;5221.634485586011;5248.973755131238;5313.521783039545;5649.265595472603;5674.148877668342;5779.537636989031;5794.82615521535;5870.677112758839;6178.69106544671;6208.410201030895;6217.0052791553135;6230.314665386803;6297.4017468340435;6249.875002499924;6331.366863313632;6342.460890824285;6355.682215889234;6365.49076073913;5703.429657034263;5881.206037184968;5907.616004959664;5922.230110085704;5943.55423343251;6216.844578885564;6287.276963149205;6295.779647712308;6300.495960323163;6307.4954003679595;6199.380061993855;6216.316205217422;6220.129181914535;6223.688815559204;6232.158658581034;6138.541671868701;6203.875922481616;6298.338674439209;6316.747330106836;6324.620522768649;6146.955017646921;6209.751609935552;6216.7824126155365;6244.906326405107;6290.087937249074;6293.811185664437;6296.464800491918;6298.685065746694;6305.5901366411035;6328.968355158287;4857.732824694605;4920.507949205123;4957.677750946177;4959.305697202394;4994.250862370601;4406.140802543538;4469.932951005738;4526.637868970474;4529.38853254813;4529.456465224194;5761.193432919395;5771.711414429262;5783.103618939072;5785.016547186989;5793.333766616893;6211.689415529296;6235.064740288903;6275.905861412082;6284.717187726564;6306.180196574508;5808.3901190375245;5818.185454036438;5829.7085145743395;5831.620944638585;5862.794802181903;5456.372517160498;5461.863453413696;5462.590305727086;5467.480589344006;5477.972610136924;6224.875502489925;6303.873922521524;6312.968435157978;6327.715252813635;6368.10846481493;6302.936970630235;6309.779157729641;6329;6333.556651034568;6336.049592561153;6410.743570257137;6411.134496843053;6419.743210271724;6429.807105786834;6432.099506069248;6095.786647467317;6105.297890742621;6117.877642447215;6121.7578389577775;6205.03821907605;6264.21697287839;6274.592151510137;6301.842453938688;6302;6403.871922561616;5967.104934259895;6099.847503812262;6101.237345331833;6108.664023478574;6121.755129794758;5990.910136347871;6061.363556826646;6101.481374083252;6113.419225173601;6119.418655227746;5866.442687944638;5986.16193732869;6017.488513476228;6025.638461692312;6088.360722124113;6139.4781443577795;6150.292716337678;6164.568480206342;6179.752809887555;6187;5763.827085187451;5912.970435147797;5957;5965.254343207099;6028.3971602839365 05:31:34 INFO - PROCESS | 1650 | |1;ext.html;460.37159277586096;468.9425345845119;489.5642877838722;505.3556715188137;545.5608235370526;30466.543001854963;41962.37056444152;42799.78600107003;42901;45188.09623807522;44002;44298.778506107505;44374;44439.77780111095;44466.33300500487;8774.078721734222;9083.863742043892;9228.307876909224;9238.907610923905;9253.583588738524;8807.559622018873;12053.397330133503;12186.634400967985;12189.573364932228;12482.750344993095;12193.63419097424;12216.389180540982;12266.938665306661;12273.89534941855;12283.26300421973;12153.817692734616;12172.208806427554;12251.081168912368;12252.019838412905;12252.387380630977;11930.821037684442;12012.579559715368;12017.519299228108;12023.63929082129;12036.81944770829;41866.79066604678;42143;42212.57787422148;42226;42286.36570451446;3705.814709264526;3856.3636999894984;4050.3519436889956;4199.349100889367;4481.215787237221;33169.00492985214;33190.33619327624;33838.66161338404;33938;34124.48813267803;2836.794362395994;3243.772935894465;3279.672032796725;3303.207230264743;3418.8290585470627;484752;485089;485300;485548;486159.5692021553;31821;63819.4045148875;64045.679771601775;64214;64218.67890660517;36463;36505.08737281589;36538.8173059133;36567.81716091456;36688.44967325492;14968;15069.54791356261;15175.848241517444;15181.316840742193;15200.391984320504;11594.304341739522;11845.76308473843;12680.93659531715;12771.552995645108;12901.806472902914;7894.171112033154;7894.289513943772;7938.206179382016;7960.1243863175005;8000.519968801774;2870.425914817045;2892.204643722951;3328.484084966834;3330.450475671487;3352.597688277422;921.0789210789211;977.2670497127154;980.367662857453;1067.8238090715101;1083.5070043130356;29224.415511689647;31127.533087003714;31208;31687.366252674816;31881.681183188335;1108.944552772358;1115.7210697325668;1132.3771925440974;1146.587228597704;1172.4430895324824;16129;16227.594310142105;16296.940698854536;16311.510654680378;16751.581210469834;700504;705106;706264;707357;707535.462322685;61522.69238653778;61944;61995;62003.6899815498;62246.377536224965;1386.6256110850215;1421.3532842556613;1450.6735984403424;1451.1148199598238;1452.2158034661165;30129.849350753106;32287.677123228466;32362.676373235965;32409.027729168163;32558.674413255565;52932.206016909004;53049.67375815481;53860.075391706094;53919.99999999843;53926;557309;565929;565939;567595.2683312128;568484;24597.754022459547;25078.874605627585;25595.104171353913;27438;28246;47221.527784721715;48888.26667599918;49056.75471622619;49783.751081244365;50409;39590;40437.76497880222;41022.38466423066;41584.58415415928;41992;37869.77516070701;40685.59314406937;40727.79636101801;40927.795361023;41481;38861.417078743274;39388.40917386299;39590.802045989585;39759.80120099497;39807.40288895611;33652.831735841166;35205.11987200341;35733.1066723334;35823.82088089543;35919;25697.743022569535;26058.86970565211;26181.60727589126;26238.86880565585;26439 05:31:34 INFO - PROCESS | 1650 | |2;jquery.html;380.07831009801225;383.0595887097177;387.50399488654523;398.3785294939892;409.56381453751754;19458.318958836444;21105;21348.57302853942;21483.355499335004;21513.03191356389;20773.792262077375;20911.790882091213;21157.894210528924;21815.23646672367;21868.45328866779;5742.942570574282;5787.855303617423;5994.520438364921;6005.87988240235;6011.669358185298;4125.463689720347;4346.695731298811;5802.361740208574;5833.445822646841;5896.528277737792;4332.0469496710675;6479.708413121397;6490.837729056764;6556.08214849919;6579.638119903403;6315.526335524854;6539.803805885807;6548.148740663707;6582.703778329987;6652.70062847173;5688.089905615123;5733.713314334266;5743.4256574342235;5746.367899531048;5763.5101016413655;26373.07694230693;26585.46829063427;26900.192994210203;26995.595066074024;27001.054963076396;2294.8393612447135;2329.720433547985;2431.1004928176512;2454.5090981803532;2504.7745702886828;15020.774688379683;15788.921055394765;15927.522174334788;16111.677766444722;16180.838191618051;1746.7641868347864;1758.9534227134834;1852.5924296654714;1867.7011678131435;1953.2870502266712;88671.55664221702;110635;112035;117903.64192716184;119651.4017429916;10968.780624387467;11087.889121108847;11170;11196.776064478665;11226.66320010401;13483.19100853952;13586.1848289101;13636.113652612548;13645.317734113254;13659.726805463835;8495.447795893371;8657.95671021641;8704.477731336141;8769.736907892773;8787.121287871161;7321.524100933529;8766.95616521926;8790.164934331227;8801.779955501164;8909.10908909117;6512.6092434454085;6592.736290548324;6619.172603424572;6624.668766561653;6642.103316052271;1815.6005678750653;1839.1264149528868;1840;1843.5944092299674;1843.7142242952364;884.4383816276711;937.2314701944476;966.79213968997;985.8669079674296;1004.2468148888333;22458.663120053214;22824.65763013556;22884.885575572014;22903;23032;976.5263847034183;994.9005099489993;1013.2805707947292;1013.7871047080048;1015.0411550330704;11262.38056906878;11554.248973816675;12117.212381195193;12157.331346775836;12547.937260313822;461988;463531;629592;652394;654184;96695.03304966861;128401;130974;147618;148623;949.2026697574071;979.7305740921304;994.6817018553993;994.8855881573567;1015.1016350529982;13907.652308692364;18412.907935460236;18934.242630294633;18955.19285777938;18970.43088707341;57317.71341143268;58604.70697646485;58975.705121474115;59241.40758592359;59252;86870;87439.1256087431;87547;87621;88390;7845.960770196112;7901.17037711032;8011.278984891388;8076.394270429624;8241.50550966944;9125.634974601207;11989.400529973293;12399;12425.93787031059;12557.560485382968;10684.305520141166;10967.35489676523;11004.39475828805;11044.950729680668;11110.16673749456;10590.205734570092;10785.676429707122;10880.238383313092;10904.50929708181;11009.669709908716;10695.037446629529;10906.781864362827;11126.054285385833;11170;11277;7242.493025488166;7912.84174316499;8020;8059.838803223785;8077.959610201911;10675.679729608124;10895.07391871668;10931.070858977077;10970.23208375438;11004.724881878017 05:31:34 INFO - PROCESS | 1650 | |3;mootools.html;1519.2707500399804;1568.9764653530196;1750.7636469076679;1928.5949950510392;1937.5834195647933;8458.577071146441;8708.085651006648;8869.20177184054;8885.777855553606;8895.733128006153;7748.380129589634;7949.125596184415;8417.11620279871;8616.009158946723;8686.695965641204;2042.213747707136;2352.7059117610256;2382.642603609455;2383.7854593086618;2385.117506522589;2339.5671800716837;2357.5756363854584;2384.9284521464297;2386.713594368678;2387.8328517003815;2078.937631871039;2152.946176345596;2156.773538778429;2160.2007257314817;2161.329987703809;1943.446117856404;2296.1274715608047;2368.786809187164;2375.2636682628267;2381;2236.910523579071;2254.6730724044924;2258.8418810683092;2263.5359751250953;2267.8866056697266;8770.386072974894;8846.292296616255;8851.336149788727;8854.291656667516;8880.289576833839;5159.458256883011;5355.3305836770405;5381.139017757179;5385.8114965976;5386.5152136307925;3524.0837382280533;3607.350676878161;3676.6323367663285;3680.429533422324;3685.1892583631557;1051.5144253106866;1073.313079629038;1101.8677758669012;1102.9172812039128;1103.4648195625116;11457.197996140358;11510.654680359603;11523.366214858179;11534.192606517547;11539.48072336747;4364.672649551233;4472.642188624902;4511.142882852248;4619.930701039487;4799.6400269979895;9810.852837207447;9835.409875407498;9856.507174641383;9872.950635246922;9885.060919212663;5350.545203657733;5354.732263386893;5367;5367.812126575551;5374.785008599691;4728.40894888139;4922.8769280768265;4925.1873440881855;4927.43334516528;4944.8763780905765;3154.7160755532113;3717.851285948586;3749.1376983293994;3783.432485127253;3813.466114743938;1099.263493459384;1102.8290614954694;1106.6790630717162;1114.804909140897;1145.8796826333114;725.793148952546;805.2189376304975;941.4868896451451;951.6336210558985;981.3982683551242;5815;5846.181534585162;5921.792737254262;6006.789762358297;6154.323024467305;447.02994501930885;449.2587231068763;464.72116729961755;466.6080492386412;468.372381009449;3930.6069393060466;4001.4397984282223;4009.258287216816;4100.0569868930315;4128.752274863457;10341.896581034243;10837.133029357632;10905.727356815984;10911.181661375429;10934.56261749537;9397.76505587352;10829.891701083046;10868.456577171268;10872.402017888933;10874.18443616732;7747.109082455477;8085.3531717462465;8105.230003149809;8216.83566328682;8233.46482478637;5798.956187886221;5936.465718085393;5968.46283834457;6013.6692481913915;6209.2548894133;9980.750481238027;10221.23340749432;10238.692839214835;10245.924177961504;10247;8744.69393571222;9532;10362.637307694195;10384.792304153723;10408.219383546113;7368.078990126234;7574.166841647525;7636.923630763621;7646;7646.388288936983;8126.268635822805;8215.178482151738;8282;8294.25351718324;8320.750377488685;6171.290301615371;7519.210482899217;7542.396608271215;7644.350230230492;7730.806729831799;6620.205575330991;7100.822479437848;7508.924910750822;7584;7587.468877178544;6709.630970296485;6955.791326260221;6962.199347075151;7007.929920700728;7011.789646310619;4500.302453119772;4548.863534093983;4598.356230127852;4602.539746025504;4625.722456652612;5041.722705251246;5087.364079490064;5121.436641969455;5195;5255.894882102413 05:31:34 INFO - PROCESS | 1650 | |4;prototype.html;268.3626387330091;301.62598378011273;311.92201949512616;317.86967343389216;318.407761563492;5105.885409899958;5434.4293849145815;5462.672239665623;5477.534409575181;5491.3135858017795;4254.404598275647;5040.36995375578;5202.661826981243;5324.831725123555;5396.514313711765;4114.588541145891;4135.255653982282;4158.230727315441;4170.186813571355;4170.186813571355;4145.917081658365;4147.523034850986;4147.917041659165;4174.269502837006;4174.979125104385;3704.5184126063573;3915;4114.128071727357;4124.257633625961;4124.803277459962;3395.8793598112566;3840.3117817527705;4047.6964227683043;4052.4124002019544;4127.380892866064;3575.78545287281;3576.2118940529735;3588.730845186595;3594.759807866282;3600.0999750062483;4402.801873915681;4414.735115893057;5056.823011194628;5322.441143679896;5326.280952071498;4750.263709125091;4751.382320298374;4778.880527986794;4792.353032340624;4829.734364609945;4728.290756386536;4760.857174284777;5720.599558030939;5748.770049198027;5772.3361813391575;3184.516077459585;3438.381091403546;3551.66812445333;3986.4054378248643;4017.2568074906235;5605.90684816461;5978.342382337939;5995.680950190951;6003.909941350883;6012.248468941382;3390.4061694603797;3932.820153953802;3951.150502641946;3990.4213888985932;3991.2815693174925;4034.65705415043;4060;4061.1877624474632;4063.89840253996;4073.327900896319;4414.404055452473;4644.256918893028;4663.207254766698;4671.229247174246;4691.9530804692195;5471.480209380103;5752.309722833287;5770.19217309577;5808.535317174616;5834.445727655866;1220.5544976083843;1285.0940087238475;1347.447546505928;1362.557168920105;1371.6502291915497;719.9352058314777;744.4751450227576;793.9325157361689;802.6348011654683;835.2816577743137;4211;4403.581659742319;4521.480029796549;4534.659900507475;4550.249208880564;4402;4922;4944.381952255968;4977;4978.651494395358;4071.5138974274178;4123.546409894969;4529.750863702462;4542.613877820356;4574.454797531939;2226.235855269692;2598.8299769692517;2599.8440093594445;2606.739326067378;2626.4221871188306;4336.913261734748;4635.976820115878;4984.451710311862;5066.544011039025;5084.262781896604;4332.6317263033;4716.358839050143;4765.259975514094;5023.447420783711;5077;4997.875053123701;5061.430956403527;5084.237364395296;5089.3638295213095;5127.564282001045;4465.419495465569;4480.61914737251;4541.818327266872;4883.975580122076;4944.777485013111;4014;4124.566920473307;4391.177661270664;4877;4887.338304976183;4851.393575803025;5023.919857230567;5047.772850221822;5074.847754567369;5081.840217907301;3014.8492575371583;3409.8124603147066;3417.504461853067;3434.9313013739084;3458;4399.5600439957025;4521.558358486381;4555.2711566149255;4638.420197475316;5253.474652534716;4570.38299829532;5670.432956704297;5727.4845263926445;5729.4843464088435;5739.684317362585;4712.481627020957;5012.47369026247;5691.317041954826;5729.341125770423;5732.7706891723865;5305.257263983122;5589.776408943597;5624.35319938196;5628.971855140698;5633.408492108433;5218.8956220876125;5222.477752224899;5714.942850571441;5719.714014299351;5733.455321744427;4907.730074845917;5273.8681532960445;5564.996601223637;5578.86052848682;5589.888202235851 05:31:34 INFO - PROCESS | 1650 | |5;yui.html;734.73549522172;830.8006078541148;832.7293629570387;857.412672319461;865.7922098696307;11987.460564274628;14967.925160374196;16253.512394628151;16283.674326513477;16303.673926521462;15084.849151508482;15202.087874727524;15237.390504379811;15254.923725381384;15275.923620381911;705.0587465733253;729.3326606155362;729.7117638532803;882.4705176893845;908.736466424736;722.0108451421534;725.2022774947568;726.5931078596003;765.3876898481193;878.0824038879355;949.4920217683541;953.2516974175273;955.3312681123207;955.9808803823919;957.7844984878416;948.7770373962113;949.1362859797551;949.178960199426;950.5959967013991;951.538503825644;551.0851985703753;682.3688088518101;691.3190507350256;708.6421357214625;710.7938697777638;13477.460901563925;15076.396944122222;15100.395984160621;15115.16866572338;15118.773218401733;13772;14201.076929999517;14223.644408889757;14226.57320280383;14388.496402625962;12607.243565386107;12634.74730505394;12659.810102848465;12765.55320563785;12817.35913204336;4602.654800889946;4776.283557466373;4787.13831510328;4788.976055119737;4842.886136188697;109591.45204274007;112734;112920;114812;115094;10947.233693641369;11251.718707032389;11537.134714896416;11622.476988535536;11645.12661550387;21912.561748764936;22066.33800985973;22094.558108838053;22108.115675373127;22109.33671989843;7852.136265010844;7937.126916039231;7944.126146123922;8075.475094118863;8077.798055048671;4382.846600368972;4395.362672412482;4442.178197033559;4452.154090722753;4628.236341003697;3421.8460169291943;3497.5453191085;3528.4707293906117;3536.734744894143;3539.4513850353237;664.2029564522592;667.9899801502982;672.4553111979312;673.0442771264718;673.491513906997;602.6986506746626;628.2021832272987;642.2998931165052;645.3320812958565;659.4559488422071;8465.576721163918;8992;9034.728958131267;9067.68263110788;9133.17933808594;501.7963716262382;623.6022493512437;635.5551114219973;639.3478651775164;652.0968458684661;4651.744154071558;4684.320773487825;4733.881652958635;4788.233882578771;4859.723682309424;130543.04185437226;156441;156823;160547.19726401294;164490;139822;140001;142587.28706356403;144925;146784.26607867106;95201;118136.8186318143;121306;123265.76734232722;123355.99999999821;9575.138237558374;9847.655332063343;9970.950145249228;9997.850032249667;10297.124744396511;58123.70938145282;60588.394116058276;62764.68617656882;64460.710785783085;64618.67690661517;92292;94777.98187118146;95539;96430;97981.04037919058;13210.735785284049;13562.79655805144;13916.930415347859;14000.159990400609;14007.299635018004;14911.85088149148;15013.77479337789;15267.694646106793;15397;15682.059076455449;14257.215853128178;14452.349644266244;14501.129932204101;14634.195119268543;14959.952803303664;13829.79255311151;13920.7627160811;14155.64610884736;14164.220967846868;14303.856961430252;11364.829527556927;14245.430182792572;14449.783253251;14459.638509037359;14525.564233073024;10118.08937195656;10226.795464090528;10275.588976440858;10348.275620706478;10778.245522813328;7738.676444214149;9473.29737890222;9836.311458197857;9928.404295742279;10388.74028149272 05:31:34 INFO - PROCESS | 1650 | __end_tp_report 05:31:34 INFO - PROCESS | 1650 | __start_cc_report 05:31:34 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1337 05:31:34 INFO - PROCESS | 1650 | __end_cc_report 05:31:34 INFO - PROCESS | 1650 | __startTimestamp1468845094602__endTimestamp 05:31:34 INFO - PROCESS | 1650 | 05:31:34 INFO - PROCESS | 1650 | ------- Summary: start ------- 05:31:34 INFO - PROCESS | 1650 | Number of tests: 6 05:31:34 INFO - PROCESS | 1650 | 05:31:34 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5787.94 Median:5990.91 stddev:634.80 (10.6%) stddev-sans-first:608.07 05:31:34 INFO - PROCESS | 1650 | Values: 3383.5 3400.0 3410.7 3434.3 3481.6 5569.0 5637.9 5692.0 5751.7 5760.1 5607.7 5642.0 5695.9 5726.3 5784.8 5133.7 5145.5 5186.2 5191.4 5332.0 5333.3 5356.9 5374.1 5379.9 5414.1 5154.3 5168.0 5201.5 5215.8 5269.6 5191.0 5222.9 5227.6 5229.2 5302.6 5194.5 5209.3 5221.6 5249.0 5313.5 5649.3 5674.1 5779.5 5794.8 5870.7 6178.7 6208.4 6217.0 6230.3 6297.4 6249.9 6331.4 6342.5 6355.7 6365.5 5703.4 5881.2 5907.6 5922.2 5943.6 6216.8 6287.3 6295.8 6300.5 6307.5 6199.4 6216.3 6220.1 6223.7 6232.2 6138.5 6203.9 6298.3 6316.7 6324.6 6147.0 6209.8 6216.8 6244.9 6290.1 6293.8 6296.5 6298.7 6305.6 6329.0 4857.7 4920.5 4957.7 4959.3 4994.3 4406.1 4469.9 4526.6 4529.4 4529.5 5761.2 5771.7 5783.1 5785.0 5793.3 6211.7 6235.1 6275.9 6284.7 6306.2 5808.4 5818.2 5829.7 5831.6 5862.8 5456.4 5461.9 5462.6 5467.5 5478.0 6224.9 6303.9 6313.0 6327.7 6368.1 6302.9 6309.8 6329.0 6333.6 6336.0 6410.7 6411.1 6419.7 6429.8 6432.1 6095.8 6105.3 6117.9 6121.8 6205.0 6264.2 6274.6 6301.8 6302.0 6403.9 5967.1 6099.8 6101.2 6108.7 6121.8 5990.9 6061.4 6101.5 6113.4 6119.4 5866.4 5986.2 6017.5 6025.6 6088.4 6139.5 6150.3 6164.6 6179.8 6187.0 5763.8 5913.0 5957.0 5965.3 6028.4 05:31:34 INFO - PROCESS | 1650 | 05:31:34 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:71387.95 Median:36553.32 stddev:158582.73 (433.8%) stddev-sans-first:158938.18 05:31:34 INFO - PROCESS | 1650 | Values: 460.4 468.9 489.6 505.4 545.6 30466.5 41962.4 42799.8 42901.0 45188.1 44002.0 44298.8 44374.0 44439.8 44466.3 8774.1 9083.9 9228.3 9238.9 9253.6 8807.6 12053.4 12186.6 12189.6 12482.8 12193.6 12216.4 12266.9 12273.9 12283.3 12153.8 12172.2 12251.1 12252.0 12252.4 11930.8 12012.6 12017.5 12023.6 12036.8 41866.8 42143.0 42212.6 42226.0 42286.4 3705.8 3856.4 4050.4 4199.3 4481.2 33169.0 33190.3 33838.7 33938.0 34124.5 2836.8 3243.8 3279.7 3303.2 3418.8 484752.0 485089.0 485300.0 485548.0 486159.6 31821.0 63819.4 64045.7 64214.0 64218.7 36463.0 36505.1 36538.8 36567.8 36688.4 14968.0 15069.5 15175.8 15181.3 15200.4 11594.3 11845.8 12680.9 12771.6 12901.8 7894.2 7894.3 7938.2 7960.1 8000.5 2870.4 2892.2 3328.5 3330.5 3352.6 921.1 977.3 980.4 1067.8 1083.5 29224.4 31127.5 31208.0 31687.4 31881.7 1108.9 1115.7 1132.4 1146.6 1172.4 16129.0 16227.6 16296.9 16311.5 16751.6 700504.0 705106.0 706264.0 707357.0 707535.5 61522.7 61944.0 61995.0 62003.7 62246.4 1386.6 1421.4 1450.7 1451.1 1452.2 30129.8 32287.7 32362.7 32409.0 32558.7 52932.2 53049.7 53860.1 53920.0 53926.0 557309.0 565929.0 565939.0 567595.3 568484.0 24597.8 25078.9 25595.1 27438.0 28246.0 47221.5 48888.3 49056.8 49783.8 50409.0 39590.0 40437.8 41022.4 41584.6 41992.0 37869.8 40685.6 40727.8 40927.8 41481.0 38861.4 39388.4 39590.8 39759.8 39807.4 33652.8 35205.1 35733.1 35823.8 35919.0 25697.7 26058.9 26181.6 26238.9 26439.0 05:31:34 INFO - PROCESS | 1650 | 05:31:34 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:34860.75 Median:2479.64 stddev:97000.40 (3911.9%) stddev-sans-first:97237.96 05:31:34 INFO - PROCESS | 1650 | Values: 380.1 383.1 387.5 398.4 409.6 19458.3 21105.0 21348.6 21483.4 21513.0 20773.8 20911.8 21157.9 21815.2 21868.5 5742.9 5787.9 5994.5 6005.9 6011.7 4125.5 4346.7 5802.4 5833.4 5896.5 4332.0 6479.7 6490.8 6556.1 6579.6 6315.5 6539.8 6548.1 6582.7 6652.7 5688.1 5733.7 5743.4 5746.4 5763.5 26373.1 26585.5 26900.2 26995.6 27001.1 2294.8 2329.7 2431.1 2454.5 2504.8 15020.8 15788.9 15927.5 16111.7 16180.8 1746.8 1759.0 1852.6 1867.7 1953.3 88671.6 110635.0 112035.0 117903.6 119651.4 10968.8 11087.9 11170.0 11196.8 11226.7 13483.2 13586.2 13636.1 13645.3 13659.7 8495.4 8658.0 8704.5 8769.7 8787.1 7321.5 8767.0 8790.2 8801.8 8909.1 6512.6 6592.7 6619.2 6624.7 6642.1 1815.6 1839.1 1840.0 1843.6 1843.7 884.4 937.2 966.8 985.9 1004.2 22458.7 22824.7 22884.9 22903.0 23032.0 976.5 994.9 1013.3 1013.8 1015.0 11262.4 11554.2 12117.2 12157.3 12547.9 461988.0 463531.0 629592.0 652394.0 654184.0 96695.0 128401.0 130974.0 147618.0 148623.0 949.2 979.7 994.7 994.9 1015.1 13907.7 18412.9 18934.2 18955.2 18970.4 57317.7 58604.7 58975.7 59241.4 59252.0 86870.0 87439.1 87547.0 87621.0 88390.0 7846.0 7901.2 8011.3 8076.4 8241.5 9125.6 11989.4 12399.0 12425.9 12557.6 10684.3 10967.4 11004.4 11045.0 11110.2 10590.2 10785.7 10880.2 10904.5 11009.7 10695.0 10906.8 11126.1 11170.0 11277.0 7242.5 7912.8 8020.0 8059.8 8078.0 10675.7 10895.1 10931.1 10970.2 11004.7 05:31:34 INFO - PROCESS | 1650 | 05:31:34 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5662.42 Median:4764.02 stddev:3218.89 (67.6%) stddev-sans-first:3212.86 05:31:34 INFO - PROCESS | 1650 | Values: 1519.3 1569.0 1750.8 1928.6 1937.6 8458.6 8708.1 8869.2 8885.8 8895.7 7748.4 7949.1 8417.1 8616.0 8686.7 2042.2 2352.7 2382.6 2383.8 2385.1 2339.6 2357.6 2384.9 2386.7 2387.8 2078.9 2152.9 2156.8 2160.2 2161.3 1943.4 2296.1 2368.8 2375.3 2381.0 2236.9 2254.7 2258.8 2263.5 2267.9 8770.4 8846.3 8851.3 8854.3 8880.3 5159.5 5355.3 5381.1 5385.8 5386.5 3524.1 3607.4 3676.6 3680.4 3685.2 1051.5 1073.3 1101.9 1102.9 1103.5 11457.2 11510.7 11523.4 11534.2 11539.5 4364.7 4472.6 4511.1 4619.9 4799.6 9810.9 9835.4 9856.5 9873.0 9885.1 5350.5 5354.7 5367.0 5367.8 5374.8 4728.4 4922.9 4925.2 4927.4 4944.9 3154.7 3717.9 3749.1 3783.4 3813.5 1099.3 1102.8 1106.7 1114.8 1145.9 725.8 805.2 941.5 951.6 981.4 5815.0 5846.2 5921.8 6006.8 6154.3 447.0 449.3 464.7 466.6 468.4 3930.6 4001.4 4009.3 4100.1 4128.8 10341.9 10837.1 10905.7 10911.2 10934.6 9397.8 10829.9 10868.5 10872.4 10874.2 7747.1 8085.4 8105.2 8216.8 8233.5 5799.0 5936.5 5968.5 6013.7 6209.3 9980.8 10221.2 10238.7 10245.9 10247.0 8744.7 9532.0 10362.6 10384.8 10408.2 7368.1 7574.2 7636.9 7646.0 7646.4 8126.3 8215.2 8282.0 8294.3 8320.8 6171.3 7519.2 7542.4 7644.4 7730.8 6620.2 7100.8 7508.9 7584.0 7587.5 6709.6 6955.8 6962.2 7007.9 7011.8 4500.3 4548.9 4598.4 4602.5 4625.7 5041.7 5087.4 5121.4 5195.0 5255.9 05:31:34 INFO - PROCESS | 1650 | 05:31:34 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4328.11 Median:4667.22 stddev:1316.93 (28.2%) stddev-sans-first:1284.89 05:31:34 INFO - PROCESS | 1650 | Values: 268.4 301.6 311.9 317.9 318.4 5105.9 5434.4 5462.7 5477.5 5491.3 4254.4 5040.4 5202.7 5324.8 5396.5 4114.6 4135.3 4158.2 4170.2 4170.2 4145.9 4147.5 4147.9 4174.3 4175.0 3704.5 3915.0 4114.1 4124.3 4124.8 3395.9 3840.3 4047.7 4052.4 4127.4 3575.8 3576.2 3588.7 3594.8 3600.1 4402.8 4414.7 5056.8 5322.4 5326.3 4750.3 4751.4 4778.9 4792.4 4829.7 4728.3 4760.9 5720.6 5748.8 5772.3 3184.5 3438.4 3551.7 3986.4 4017.3 5605.9 5978.3 5995.7 6003.9 6012.2 3390.4 3932.8 3951.2 3990.4 3991.3 4034.7 4060.0 4061.2 4063.9 4073.3 4414.4 4644.3 4663.2 4671.2 4692.0 5471.5 5752.3 5770.2 5808.5 5834.4 1220.6 1285.1 1347.4 1362.6 1371.7 719.9 744.5 793.9 802.6 835.3 4211.0 4403.6 4521.5 4534.7 4550.2 4402.0 4922.0 4944.4 4977.0 4978.7 4071.5 4123.5 4529.8 4542.6 4574.5 2226.2 2598.8 2599.8 2606.7 2626.4 4336.9 4636.0 4984.5 5066.5 5084.3 4332.6 4716.4 4765.3 5023.4 5077.0 4997.9 5061.4 5084.2 5089.4 5127.6 4465.4 4480.6 4541.8 4884.0 4944.8 4014.0 4124.6 4391.2 4877.0 4887.3 4851.4 5023.9 5047.8 5074.8 5081.8 3014.8 3409.8 3417.5 3434.9 3458.0 4399.6 4521.6 4555.3 4638.4 5253.5 4570.4 5670.4 5727.5 5729.5 5739.7 4712.5 5012.5 5691.3 5729.3 5732.8 5305.3 5589.8 5624.4 5629.0 5633.4 5218.9 5222.5 5714.9 5719.7 5733.5 4907.7 5273.9 5565.0 5578.9 5589.9 05:31:34 INFO - PROCESS | 1650 | 05:31:34 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:25869.69 Median:3961.15 stddev:41845.71 (1056.4%) stddev-sans-first:41920.54 05:31:34 INFO - PROCESS | 1650 | Values: 734.7 830.8 832.7 857.4 865.8 11987.5 14967.9 16253.5 16283.7 16303.7 15084.8 15202.1 15237.4 15254.9 15275.9 705.1 729.3 729.7 882.5 908.7 722.0 725.2 726.6 765.4 878.1 949.5 953.3 955.3 956.0 957.8 948.8 949.1 949.2 950.6 951.5 551.1 682.4 691.3 708.6 710.8 13477.5 15076.4 15100.4 15115.2 15118.8 13772.0 14201.1 14223.6 14226.6 14388.5 12607.2 12634.7 12659.8 12765.6 12817.4 4602.7 4776.3 4787.1 4789.0 4842.9 109591.5 112734.0 112920.0 114812.0 115094.0 10947.2 11251.7 11537.1 11622.5 11645.1 21912.6 22066.3 22094.6 22108.1 22109.3 7852.1 7937.1 7944.1 8075.5 8077.8 4382.8 4395.4 4442.2 4452.2 4628.2 3421.8 3497.5 3528.5 3536.7 3539.5 664.2 668.0 672.5 673.0 673.5 602.7 628.2 642.3 645.3 659.5 8465.6 8992.0 9034.7 9067.7 9133.2 501.8 623.6 635.6 639.3 652.1 4651.7 4684.3 4733.9 4788.2 4859.7 130543.0 156441.0 156823.0 160547.2 164490.0 139822.0 140001.0 142587.3 144925.0 146784.3 95201.0 118136.8 121306.0 123265.8 123356.0 9575.1 9847.7 9971.0 9997.9 10297.1 58123.7 60588.4 62764.7 64460.7 64618.7 92292.0 94778.0 95539.0 96430.0 97981.0 13210.7 13562.8 13916.9 14000.2 14007.3 14911.9 15013.8 15267.7 15397.0 15682.1 14257.2 14452.3 14501.1 14634.2 14960.0 13829.8 13920.8 14155.6 14164.2 14303.9 11364.8 14245.4 14449.8 14459.6 14525.6 10118.1 10226.8 10275.6 10348.3 10778.2 7738.7 9473.3 9836.3 9928.4 10388.7 05:31:34 INFO - PROCESS | 1650 | -------- Summary: end -------- 05:31:34 INFO - PROCESS | 1650 | 05:31:35 INFO - TEST-INFO | 1650: exit 0 05:31:35 INFO - TEST-OK | dromaeo_css | took 1116514ms 05:31:35 INFO - TEST-START | kraken 05:31:35 INFO - operating with platform_type : mac_ 05:31:35 INFO - Initialising browser for kraken test... 05:31:35 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/tmppQxD1B/profile http://localhost:49271/getInfo.html) 05:31:37 INFO - PROCESS | 1657 | JavaScript error: , line 0: uncaught exception: undefined 05:31:37 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 05:31:37 INFO - PROCESS | 1657 | colorDepth:24 05:31:37 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 05:31:37 INFO - PROCESS | 1657 | __metrics 05:31:37 INFO - PROCESS | 1657 | 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:31:37 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:31:38 INFO - TEST-INFO | 1657: exit 0 05:31:38 INFO - Browser initialized. 05:31:38 INFO - Running cycle 1/1 for kraken test... 05:31:38 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/tmppQxD1B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:50 INFO - PROCESS | 1658 | RSS: Main: 245096448 05:31:50 INFO - PROCESS | 1658 | 05:31:52 INFO - PROCESS | 1658 | 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:31:53 INFO - PROCESS | 1658 | RSS: Main: 295342080 05:31:53 INFO - PROCESS | 1658 | 05:31:56 INFO - PROCESS | 1658 | 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:31:57 INFO - PROCESS | 1658 | RSS: Main: 301551616 05:31:57 INFO - PROCESS | 1658 | 05:32:00 INFO - PROCESS | 1658 | 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:32:01 INFO - PROCESS | 1658 | RSS: Main: 391442432 05:32:01 INFO - PROCESS | 1658 | 05:32:03 INFO - PROCESS | 1658 | 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:32:03 INFO - PROCESS | 1658 | RSS: Main: 294256640 05:32:03 INFO - PROCESS | 1658 | 05:32:05 INFO - PROCESS | 1658 | 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:32:06 INFO - PROCESS | 1658 | RSS: Main: 298344448 05:32:06 INFO - PROCESS | 1658 | 05:32:09 INFO - PROCESS | 1658 | 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:32:09 INFO - PROCESS | 1658 | RSS: Main: 333197312 05:32:09 INFO - PROCESS | 1658 | 05:32:12 INFO - PROCESS | 1658 | 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:32:13 INFO - PROCESS | 1658 | RSS: Main: 336429056 05:32:13 INFO - PROCESS | 1658 | 05:32:16 INFO - PROCESS | 1658 | 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:32:16 INFO - PROCESS | 1658 | RSS: Main: 337862656 05:32:16 INFO - PROCESS | 1658 | 05:32:18 INFO - PROCESS | 1658 | 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:32:18 INFO - PROCESS | 1658 | RSS: Main: 313823232 05:32:18 INFO - PROCESS | 1658 | 05:32:20 INFO - PROCESS | 1658 | 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:32:21 INFO - PROCESS | 1658 | RSS: Main: 320958464 05:32:21 INFO - PROCESS | 1658 | 05:32:23 INFO - PROCESS | 1658 | 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:32:24 INFO - PROCESS | 1658 | RSS: Main: 318889984 05:32:24 INFO - PROCESS | 1658 | 05:32:27 INFO - PROCESS | 1658 | 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:32:27 INFO - PROCESS | 1658 | RSS: Main: 328359936 05:32:27 INFO - PROCESS | 1658 | 05:32:30 INFO - PROCESS | 1658 | 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:32:31 INFO - PROCESS | 1658 | RSS: Main: 330956800 05:32:31 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | 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:32:33 INFO - PROCESS | 1658 | RSS: Main: 373899264 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | __start_tp_report 05:32:33 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 05:32:33 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 05:32:33 INFO - PROCESS | 1658 | |i|pagename|runs| 05:32:33 INFO - PROCESS | 1658 | |0;ai-astar;104;115;112;114;114;114;115;113;113;114 05:32:33 INFO - PROCESS | 1658 | |1;audio-beat-detection;141;115;105;156;107;109;128;103;138;103 05:32:33 INFO - PROCESS | 1658 | |2;audio-dft;182;184;183;186;181;182;180;182;188;182 05:32:33 INFO - PROCESS | 1658 | |3;audio-fft;79;57;56;104;57;74;89;78;84;57 05:32:33 INFO - PROCESS | 1658 | |4;audio-oscillator;88;68;89;71;78;69;96;69;68;100 05:32:33 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;117;127;101;85;87;84;88;84;87;83 05:32:33 INFO - PROCESS | 1658 | |6;imaging-darkroom;94;93;94;95;94;96;94;88;129;93 05:32:33 INFO - PROCESS | 1658 | |7;imaging-desaturate;85;85;83;83;81;87;85;86;85;86 05:32:33 INFO - PROCESS | 1658 | |8;json-parse-financial;53;54;53;53;53;54;53;53;52;54 05:32:33 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;66;76;79;78;74;71;72;70;71;74 05:32:33 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;86;82;82;83;79;89;100;99;80;84 05:32:33 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;139;142;134;143;150;146;148;135;238;156 05:32:33 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;169;170;172;172;169;171;171;170;172;237 05:32:33 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;68;67;66;67;68;69;81;86;84 05:32:33 INFO - PROCESS | 1658 | __end_tp_report 05:32:33 INFO - PROCESS | 1658 | __start_cc_report 05:32:33 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,2025 05:32:33 INFO - PROCESS | 1658 | __end_cc_report 05:32:33 INFO - PROCESS | 1658 | __startTimestamp1468845153560__endTimestamp 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | ------- Summary: start ------- 05:32:33 INFO - PROCESS | 1658 | Number of tests: 14 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.22 (2.8%) stddev-sans-first:0.97 05:32:33 INFO - PROCESS | 1658 | Values: 104.0 115.0 112.0 114.0 114.0 114.0 115.0 113.0 113.0 114.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:120.50 Median:121.50 stddev:18.98 (15.6%) stddev-sans-first:18.62 05:32:33 INFO - PROCESS | 1658 | Values: 141.0 115.0 105.0 156.0 107.0 109.0 128.0 103.0 138.0 103.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:183.00 Median:182.50 stddev:2.40 (1.3%) stddev-sans-first:2.52 05:32:33 INFO - PROCESS | 1658 | Values: 182.0 184.0 183.0 186.0 181.0 182.0 180.0 182.0 188.0 182.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:73.50 Median:76.00 stddev:16.51 (21.7%) stddev-sans-first:17.40 05:32:33 INFO - PROCESS | 1658 | Values: 79.0 57.0 56.0 104.0 57.0 74.0 89.0 78.0 84.0 57.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:74.50 stddev:12.54 (16.8%) stddev-sans-first:12.92 05:32:33 INFO - PROCESS | 1658 | Values: 88.0 68.0 89.0 71.0 78.0 69.0 96.0 69.0 68.0 100.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:84.50 stddev:15.64 (18.5%) stddev-sans-first:14.27 05:32:33 INFO - PROCESS | 1658 | Values: 117.0 127.0 101.0 85.0 87.0 84.0 88.0 84.0 87.0 83.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:94.00 stddev:11.44 (12.2%) stddev-sans-first:12.08 05:32:33 INFO - PROCESS | 1658 | Values: 94.0 93.0 94.0 95.0 94.0 96.0 94.0 88.0 129.0 93.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.78 (2.1%) stddev-sans-first:1.88 05:32:33 INFO - PROCESS | 1658 | Values: 85.0 85.0 83.0 83.0 81.0 87.0 85.0 86.0 85.0 86.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 05:32:33 INFO - PROCESS | 1658 | Values: 53.0 54.0 53.0 53.0 53.0 54.0 53.0 53.0 52.0 54.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:74.00 stddev:3.93 (5.3%) stddev-sans-first:3.22 05:32:33 INFO - PROCESS | 1658 | Values: 66.0 76.0 79.0 78.0 74.0 71.0 72.0 70.0 71.0 74.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:83.50 stddev:7.47 (8.9%) stddev-sans-first:7.92 05:32:33 INFO - PROCESS | 1658 | Values: 86.0 82.0 82.0 83.0 79.0 89.0 100.0 99.0 80.0 84.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:147.00 stddev:30.58 (20.8%) stddev-sans-first:32.01 05:32:33 INFO - PROCESS | 1658 | Values: 139.0 142.0 134.0 143.0 150.0 146.0 148.0 135.0 238.0 156.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:171.50 stddev:21.01 (12.2%) stddev-sans-first:22.07 05:32:33 INFO - PROCESS | 1658 | Values: 169.0 170.0 172.0 172.0 169.0 171.0 171.0 170.0 172.0 237.0 05:32:33 INFO - PROCESS | 1658 | 05:32:33 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:68.50 stddev:7.97 (11.6%) stddev-sans-first:8.22 05:32:33 INFO - PROCESS | 1658 | Values: 67.0 68.0 67.0 66.0 67.0 68.0 69.0 81.0 86.0 84.0 05:32:33 INFO - PROCESS | 1658 | -------- Summary: end -------- 05:32:33 INFO - PROCESS | 1658 | 05:32:34 INFO - TEST-INFO | 1658: exit 0 05:32:34 INFO - TEST-OK | kraken | took 59155ms 05:32:34 INFO - SUITE-END | took 1175s 05:32:34 INFO - Completed test suite (00:19:35) 05:32:34 INFO - javascript benchmark 05:32:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3383.4755612880003, 3399.9830000850006, 3410.727141828653, 3434.347473979942, 3481.56480439945, 5569.0, 5637.91543126853, 5691.971540142294, 5751.741171647275, 5760.1359796030565, 5607.691576963265, 5641.971790141043, 5695.8576035599135, 5726.255586773714, 5784.768609255635, 5133.743312834344, 5145.459726728695, 5186.19613959835, 5191.428942816287, 5332.040232758102, 5333.280007199017, 5356.91964620531, 5374.086405311087, 5379.919301210485, 5414.10667239905, 5154.252633368178, 5168.0, 5201.479852014806, 5215.817446389358, 5269.604779641523, 5191.0, 5222.869428264324, 5227.634065615371, 5229.189475631283, 5302.628815982883, 5194.480551944813, 5209.322788037531, 5221.634485586011, 5248.973755131238, 5313.521783039545, 5649.265595472603, 5674.148877668342, 5779.537636989031, 5794.82615521535, 5870.677112758839, 6178.69106544671, 6208.410201030895, 6217.0052791553135, 6230.314665386803, 6297.4017468340435, 6249.875002499924, 6331.366863313632, 6342.460890824285, 6355.682215889234, 6365.49076073913, 5703.429657034263, 5881.206037184968, 5907.616004959664, 5922.230110085704, 5943.55423343251, 6216.844578885564, 6287.276963149205, 6295.779647712308, 6300.495960323163, 6307.4954003679595, 6199.380061993855, 6216.316205217422, 6220.129181914535, 6223.688815559204, 6232.158658581034, 6138.541671868701, 6203.875922481616, 6298.338674439209, 6316.747330106836, 6324.620522768649, 6146.955017646921, 6209.751609935552, 6216.7824126155365, 6244.906326405107, 6290.087937249074, 6293.811185664437, 6296.464800491918, 6298.685065746694, 6305.5901366411035, 6328.968355158287, 4857.732824694605, 4920.507949205123, 4957.677750946177, 4959.305697202394, 4994.250862370601, 4406.140802543538, 4469.932951005738, 4526.637868970474, 4529.38853254813, 4529.456465224194, 5761.193432919395, 5771.711414429262, 5783.103618939072, 5785.016547186989, 5793.333766616893, 6211.689415529296, 6235.064740288903, 6275.905861412082, 6284.717187726564, 6306.180196574508, 5808.3901190375245, 5818.185454036438, 5829.7085145743395, 5831.620944638585, 5862.794802181903, 5456.372517160498, 5461.863453413696, 5462.590305727086, 5467.480589344006, 5477.972610136924, 6224.875502489925, 6303.873922521524, 6312.968435157978, 6327.715252813635, 6368.10846481493, 6302.936970630235, 6309.779157729641, 6329.0, 6333.556651034568, 6336.049592561153, 6410.743570257137, 6411.134496843053, 6419.743210271724, 6429.807105786834, 6432.099506069248, 6095.786647467317, 6105.297890742621, 6117.877642447215, 6121.7578389577775, 6205.03821907605, 6264.21697287839, 6274.592151510137, 6301.842453938688, 6302.0, 6403.871922561616, 5967.104934259895, 6099.847503812262, 6101.237345331833, 6108.664023478574, 6121.755129794758, 5990.910136347871, 6061.363556826646, 6101.481374083252, 6113.419225173601, 6119.418655227746, 5866.442687944638, 5986.16193732869, 6017.488513476228, 6025.638461692312, 6088.360722124113, 6139.4781443577795, 6150.292716337678, 6164.568480206342, 6179.752809887555, 6187.0, 5763.827085187451, 5912.970435147797, 5957.0, 5965.254343207099, 6028.3971602839365], "value": 5747.082923985172, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.37159277586096, 468.9425345845119, 489.5642877838722, 505.3556715188137, 545.5608235370526, 30466.543001854963, 41962.37056444152, 42799.78600107003, 42901.0, 45188.09623807522, 44002.0, 44298.778506107505, 44374.0, 44439.77780111095, 44466.33300500487, 8774.078721734222, 9083.863742043892, 9228.307876909224, 9238.907610923905, 9253.583588738524, 8807.559622018873, 12053.397330133503, 12186.634400967985, 12189.573364932228, 12482.750344993095, 12193.63419097424, 12216.389180540982, 12266.938665306661, 12273.89534941855, 12283.26300421973, 12153.817692734616, 12172.208806427554, 12251.081168912368, 12252.019838412905, 12252.387380630977, 11930.821037684442, 12012.579559715368, 12017.519299228108, 12023.63929082129, 12036.81944770829, 41866.79066604678, 42143.0, 42212.57787422148, 42226.0, 42286.36570451446, 3705.814709264526, 3856.3636999894984, 4050.3519436889956, 4199.349100889367, 4481.215787237221, 33169.00492985214, 33190.33619327624, 33838.66161338404, 33938.0, 34124.48813267803, 2836.794362395994, 3243.772935894465, 3279.672032796725, 3303.207230264743, 3418.8290585470627, 484752.0, 485089.0, 485300.0, 485548.0, 486159.5692021553, 31821.0, 63819.4045148875, 64045.679771601775, 64214.0, 64218.67890660517, 36463.0, 36505.08737281589, 36538.8173059133, 36567.81716091456, 36688.44967325492, 14968.0, 15069.54791356261, 15175.848241517444, 15181.316840742193, 15200.391984320504, 11594.304341739522, 11845.76308473843, 12680.93659531715, 12771.552995645108, 12901.806472902914, 7894.171112033154, 7894.289513943772, 7938.206179382016, 7960.1243863175005, 8000.519968801774, 2870.425914817045, 2892.204643722951, 3328.484084966834, 3330.450475671487, 3352.597688277422, 921.0789210789211, 977.2670497127154, 980.367662857453, 1067.8238090715101, 1083.5070043130356, 29224.415511689647, 31127.533087003714, 31208.0, 31687.366252674816, 31881.681183188335, 1108.944552772358, 1115.7210697325668, 1132.3771925440974, 1146.587228597704, 1172.4430895324824, 16129.0, 16227.594310142105, 16296.940698854536, 16311.510654680378, 16751.581210469834, 700504.0, 705106.0, 706264.0, 707357.0, 707535.462322685, 61522.69238653778, 61944.0, 61995.0, 62003.6899815498, 62246.377536224965, 1386.6256110850215, 1421.3532842556613, 1450.6735984403424, 1451.1148199598238, 1452.2158034661165, 30129.849350753106, 32287.677123228466, 32362.676373235965, 32409.027729168163, 32558.674413255565, 52932.206016909004, 53049.67375815481, 53860.075391706094, 53919.99999999843, 53926.0, 557309.0, 565929.0, 565939.0, 567595.2683312128, 568484.0, 24597.754022459547, 25078.874605627585, 25595.104171353913, 27438.0, 28246.0, 47221.527784721715, 48888.26667599918, 49056.75471622619, 49783.751081244365, 50409.0, 39590.0, 40437.76497880222, 41022.38466423066, 41584.58415415928, 41992.0, 37869.77516070701, 40685.59314406937, 40727.79636101801, 40927.795361023, 41481.0, 38861.417078743274, 39388.40917386299, 39590.802045989585, 39759.80120099497, 39807.40288895611, 33652.831735841166, 35205.11987200341, 35733.1066723334, 35823.82088089543, 35919.0, 25697.743022569535, 26058.86970565211, 26181.60727589126, 26238.86880565585, 26439.0], "value": 19708.091920339157, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.07831009801225, 383.0595887097177, 387.50399488654523, 398.3785294939892, 409.56381453751754, 19458.318958836444, 21105.0, 21348.57302853942, 21483.355499335004, 21513.03191356389, 20773.792262077375, 20911.790882091213, 21157.894210528924, 21815.23646672367, 21868.45328866779, 5742.942570574282, 5787.855303617423, 5994.520438364921, 6005.87988240235, 6011.669358185298, 4125.463689720347, 4346.695731298811, 5802.361740208574, 5833.445822646841, 5896.528277737792, 4332.0469496710675, 6479.708413121397, 6490.837729056764, 6556.08214849919, 6579.638119903403, 6315.526335524854, 6539.803805885807, 6548.148740663707, 6582.703778329987, 6652.70062847173, 5688.089905615123, 5733.713314334266, 5743.4256574342235, 5746.367899531048, 5763.5101016413655, 26373.07694230693, 26585.46829063427, 26900.192994210203, 26995.595066074024, 27001.054963076396, 2294.8393612447135, 2329.720433547985, 2431.1004928176512, 2454.5090981803532, 2504.7745702886828, 15020.774688379683, 15788.921055394765, 15927.522174334788, 16111.677766444722, 16180.838191618051, 1746.7641868347864, 1758.9534227134834, 1852.5924296654714, 1867.7011678131435, 1953.2870502266712, 88671.55664221702, 110635.0, 112035.0, 117903.64192716184, 119651.4017429916, 10968.780624387467, 11087.889121108847, 11170.0, 11196.776064478665, 11226.66320010401, 13483.19100853952, 13586.1848289101, 13636.113652612548, 13645.317734113254, 13659.726805463835, 8495.447795893371, 8657.95671021641, 8704.477731336141, 8769.736907892773, 8787.121287871161, 7321.524100933529, 8766.95616521926, 8790.164934331227, 8801.779955501164, 8909.10908909117, 6512.6092434454085, 6592.736290548324, 6619.172603424572, 6624.668766561653, 6642.103316052271, 1815.6005678750653, 1839.1264149528868, 1840.0, 1843.5944092299674, 1843.7142242952364, 884.4383816276711, 937.2314701944476, 966.79213968997, 985.8669079674296, 1004.2468148888333, 22458.663120053214, 22824.65763013556, 22884.885575572014, 22903.0, 23032.0, 976.5263847034183, 994.9005099489993, 1013.2805707947292, 1013.7871047080048, 1015.0411550330704, 11262.38056906878, 11554.248973816675, 12117.212381195193, 12157.331346775836, 12547.937260313822, 461988.0, 463531.0, 629592.0, 652394.0, 654184.0, 96695.03304966861, 128401.0, 130974.0, 147618.0, 148623.0, 949.2026697574071, 979.7305740921304, 994.6817018553993, 994.8855881573567, 1015.1016350529982, 13907.652308692364, 18412.907935460236, 18934.242630294633, 18955.19285777938, 18970.43088707341, 57317.71341143268, 58604.70697646485, 58975.705121474115, 59241.40758592359, 59252.0, 86870.0, 87439.1256087431, 87547.0, 87621.0, 88390.0, 7845.960770196112, 7901.17037711032, 8011.278984891388, 8076.394270429624, 8241.50550966944, 9125.634974601207, 11989.400529973293, 12399.0, 12425.93787031059, 12557.560485382968, 10684.305520141166, 10967.35489676523, 11004.39475828805, 11044.950729680668, 11110.16673749456, 10590.205734570092, 10785.676429707122, 10880.238383313092, 10904.50929708181, 11009.669709908716, 10695.037446629529, 10906.781864362827, 11126.054285385833, 11170.0, 11277.0, 7242.493025488166, 7912.84174316499, 8020.0, 8059.838803223785, 8077.959610201911, 10675.679729608124, 10895.07391871668, 10931.070858977077, 10970.23208375438, 11004.724881878017], "value": 9875.13207500835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.2707500399804, 1568.9764653530196, 1750.7636469076679, 1928.5949950510392, 1937.5834195647933, 8458.577071146441, 8708.085651006648, 8869.20177184054, 8885.777855553606, 8895.733128006153, 7748.380129589634, 7949.125596184415, 8417.11620279871, 8616.009158946723, 8686.695965641204, 2042.213747707136, 2352.7059117610256, 2382.642603609455, 2383.7854593086618, 2385.117506522589, 2339.5671800716837, 2357.5756363854584, 2384.9284521464297, 2386.713594368678, 2387.8328517003815, 2078.937631871039, 2152.946176345596, 2156.773538778429, 2160.2007257314817, 2161.329987703809, 1943.446117856404, 2296.1274715608047, 2368.786809187164, 2375.2636682628267, 2381.0, 2236.910523579071, 2254.6730724044924, 2258.8418810683092, 2263.5359751250953, 2267.8866056697266, 8770.386072974894, 8846.292296616255, 8851.336149788727, 8854.291656667516, 8880.289576833839, 5159.458256883011, 5355.3305836770405, 5381.139017757179, 5385.8114965976, 5386.5152136307925, 3524.0837382280533, 3607.350676878161, 3676.6323367663285, 3680.429533422324, 3685.1892583631557, 1051.5144253106866, 1073.313079629038, 1101.8677758669012, 1102.9172812039128, 1103.4648195625116, 11457.197996140358, 11510.654680359603, 11523.366214858179, 11534.192606517547, 11539.48072336747, 4364.672649551233, 4472.642188624902, 4511.142882852248, 4619.930701039487, 4799.6400269979895, 9810.852837207447, 9835.409875407498, 9856.507174641383, 9872.950635246922, 9885.060919212663, 5350.545203657733, 5354.732263386893, 5367.0, 5367.812126575551, 5374.785008599691, 4728.40894888139, 4922.8769280768265, 4925.1873440881855, 4927.43334516528, 4944.8763780905765, 3154.7160755532113, 3717.851285948586, 3749.1376983293994, 3783.432485127253, 3813.466114743938, 1099.263493459384, 1102.8290614954694, 1106.6790630717162, 1114.804909140897, 1145.8796826333114, 725.793148952546, 805.2189376304975, 941.4868896451451, 951.6336210558985, 981.3982683551242, 5815.0, 5846.181534585162, 5921.792737254262, 6006.789762358297, 6154.323024467305, 447.02994501930885, 449.2587231068763, 464.72116729961755, 466.6080492386412, 468.372381009449, 3930.6069393060466, 4001.4397984282223, 4009.258287216816, 4100.0569868930315, 4128.752274863457, 10341.896581034243, 10837.133029357632, 10905.727356815984, 10911.181661375429, 10934.56261749537, 9397.76505587352, 10829.891701083046, 10868.456577171268, 10872.402017888933, 10874.18443616732, 7747.109082455477, 8085.3531717462465, 8105.230003149809, 8216.83566328682, 8233.46482478637, 5798.956187886221, 5936.465718085393, 5968.46283834457, 6013.6692481913915, 6209.2548894133, 9980.750481238027, 10221.23340749432, 10238.692839214835, 10245.924177961504, 10247.0, 8744.69393571222, 9532.0, 10362.637307694195, 10384.792304153723, 10408.219383546113, 7368.078990126234, 7574.166841647525, 7636.923630763621, 7646.0, 7646.388288936983, 8126.268635822805, 8215.178482151738, 8282.0, 8294.25351718324, 8320.750377488685, 6171.290301615371, 7519.210482899217, 7542.396608271215, 7644.350230230492, 7730.806729831799, 6620.205575330991, 7100.822479437848, 7508.924910750822, 7584.0, 7587.468877178544, 6709.630970296485, 6955.791326260221, 6962.199347075151, 7007.929920700728, 7011.789646310619, 4500.302453119772, 4548.863534093983, 4598.356230127852, 4602.539746025504, 4625.722456652612, 5041.722705251246, 5087.364079490064, 5121.436641969455, 5195.0, 5255.894882102413], "value": 4455.118146304665, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.3626387330091, 301.62598378011273, 311.92201949512616, 317.86967343389216, 318.407761563492, 5105.885409899958, 5434.4293849145815, 5462.672239665623, 5477.534409575181, 5491.3135858017795, 4254.404598275647, 5040.36995375578, 5202.661826981243, 5324.831725123555, 5396.514313711765, 4114.588541145891, 4135.255653982282, 4158.230727315441, 4170.186813571355, 4170.186813571355, 4145.917081658365, 4147.523034850986, 4147.917041659165, 4174.269502837006, 4174.979125104385, 3704.5184126063573, 3915.0, 4114.128071727357, 4124.257633625961, 4124.803277459962, 3395.8793598112566, 3840.3117817527705, 4047.6964227683043, 4052.4124002019544, 4127.380892866064, 3575.78545287281, 3576.2118940529735, 3588.730845186595, 3594.759807866282, 3600.0999750062483, 4402.801873915681, 4414.735115893057, 5056.823011194628, 5322.441143679896, 5326.280952071498, 4750.263709125091, 4751.382320298374, 4778.880527986794, 4792.353032340624, 4829.734364609945, 4728.290756386536, 4760.857174284777, 5720.599558030939, 5748.770049198027, 5772.3361813391575, 3184.516077459585, 3438.381091403546, 3551.66812445333, 3986.4054378248643, 4017.2568074906235, 5605.90684816461, 5978.342382337939, 5995.680950190951, 6003.909941350883, 6012.248468941382, 3390.4061694603797, 3932.820153953802, 3951.150502641946, 3990.4213888985932, 3991.2815693174925, 4034.65705415043, 4060.0, 4061.1877624474632, 4063.89840253996, 4073.327900896319, 4414.404055452473, 4644.256918893028, 4663.207254766698, 4671.229247174246, 4691.9530804692195, 5471.480209380103, 5752.309722833287, 5770.19217309577, 5808.535317174616, 5834.445727655866, 1220.5544976083843, 1285.0940087238475, 1347.447546505928, 1362.557168920105, 1371.6502291915497, 719.9352058314777, 744.4751450227576, 793.9325157361689, 802.6348011654683, 835.2816577743137, 4211.0, 4403.581659742319, 4521.480029796549, 4534.659900507475, 4550.249208880564, 4402.0, 4922.0, 4944.381952255968, 4977.0, 4978.651494395358, 4071.5138974274178, 4123.546409894969, 4529.750863702462, 4542.613877820356, 4574.454797531939, 2226.235855269692, 2598.8299769692517, 2599.8440093594445, 2606.739326067378, 2626.4221871188306, 4336.913261734748, 4635.976820115878, 4984.451710311862, 5066.544011039025, 5084.262781896604, 4332.6317263033, 4716.358839050143, 4765.259975514094, 5023.447420783711, 5077.0, 4997.875053123701, 5061.430956403527, 5084.237364395296, 5089.3638295213095, 5127.564282001045, 4465.419495465569, 4480.61914737251, 4541.818327266872, 4883.975580122076, 4944.777485013111, 4014.0, 4124.566920473307, 4391.177661270664, 4877.0, 4887.338304976183, 4851.393575803025, 5023.919857230567, 5047.772850221822, 5074.847754567369, 5081.840217907301, 3014.8492575371583, 3409.8124603147066, 3417.504461853067, 3434.9313013739084, 3458.0, 4399.5600439957025, 4521.558358486381, 4555.2711566149255, 4638.420197475316, 5253.474652534716, 4570.38299829532, 5670.432956704297, 5727.4845263926445, 5729.4843464088435, 5739.684317362585, 4712.481627020957, 5012.47369026247, 5691.317041954826, 5729.341125770423, 5732.7706891723865, 5305.257263983122, 5589.776408943597, 5624.35319938196, 5628.971855140698, 5633.408492108433, 5218.8956220876125, 5222.477752224899, 5714.942850571441, 5719.714014299351, 5733.455321744427, 4907.730074845917, 5273.8681532960445, 5564.996601223637, 5578.86052848682, 5589.888202235851], "value": 3906.9050810395324, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.73549522172, 830.8006078541148, 832.7293629570387, 857.412672319461, 865.7922098696307, 11987.460564274628, 14967.925160374196, 16253.512394628151, 16283.674326513477, 16303.673926521462, 15084.849151508482, 15202.087874727524, 15237.390504379811, 15254.923725381384, 15275.923620381911, 705.0587465733253, 729.3326606155362, 729.7117638532803, 882.4705176893845, 908.736466424736, 722.0108451421534, 725.2022774947568, 726.5931078596003, 765.3876898481193, 878.0824038879355, 949.4920217683541, 953.2516974175273, 955.3312681123207, 955.9808803823919, 957.7844984878416, 948.7770373962113, 949.1362859797551, 949.178960199426, 950.5959967013991, 951.538503825644, 551.0851985703753, 682.3688088518101, 691.3190507350256, 708.6421357214625, 710.7938697777638, 13477.460901563925, 15076.396944122222, 15100.395984160621, 15115.16866572338, 15118.773218401733, 13772.0, 14201.076929999517, 14223.644408889757, 14226.57320280383, 14388.496402625962, 12607.243565386107, 12634.74730505394, 12659.810102848465, 12765.55320563785, 12817.35913204336, 4602.654800889946, 4776.283557466373, 4787.13831510328, 4788.976055119737, 4842.886136188697, 109591.45204274007, 112734.0, 112920.0, 114812.0, 115094.0, 10947.233693641369, 11251.718707032389, 11537.134714896416, 11622.476988535536, 11645.12661550387, 21912.561748764936, 22066.33800985973, 22094.558108838053, 22108.115675373127, 22109.33671989843, 7852.136265010844, 7937.126916039231, 7944.126146123922, 8075.475094118863, 8077.798055048671, 4382.846600368972, 4395.362672412482, 4442.178197033559, 4452.154090722753, 4628.236341003697, 3421.8460169291943, 3497.5453191085, 3528.4707293906117, 3536.734744894143, 3539.4513850353237, 664.2029564522592, 667.9899801502982, 672.4553111979312, 673.0442771264718, 673.491513906997, 602.6986506746626, 628.2021832272987, 642.2998931165052, 645.3320812958565, 659.4559488422071, 8465.576721163918, 8992.0, 9034.728958131267, 9067.68263110788, 9133.17933808594, 501.7963716262382, 623.6022493512437, 635.5551114219973, 639.3478651775164, 652.0968458684661, 4651.744154071558, 4684.320773487825, 4733.881652958635, 4788.233882578771, 4859.723682309424, 130543.04185437226, 156441.0, 156823.0, 160547.19726401294, 164490.0, 139822.0, 140001.0, 142587.28706356403, 144925.0, 146784.26607867106, 95201.0, 118136.8186318143, 121306.0, 123265.76734232722, 123355.99999999821, 9575.138237558374, 9847.655332063343, 9970.950145249228, 9997.850032249667, 10297.124744396511, 58123.70938145282, 60588.394116058276, 62764.68617656882, 64460.710785783085, 64618.67690661517, 92292.0, 94777.98187118146, 95539.0, 96430.0, 97981.04037919058, 13210.735785284049, 13562.79655805144, 13916.930415347859, 14000.159990400609, 14007.299635018004, 14911.85088149148, 15013.77479337789, 15267.694646106793, 15397.0, 15682.059076455449, 14257.215853128178, 14452.349644266244, 14501.129932204101, 14634.195119268543, 14959.952803303664, 13829.79255311151, 13920.7627160811, 14155.64610884736, 14164.220967846868, 14303.856961430252, 11364.829527556927, 14245.430182792572, 14449.783253251, 14459.638509037359, 14525.564233073024, 10118.08937195656, 10226.795464090528, 10275.588976440858, 10348.275620706478, 10778.245522813328, 7738.676444214149, 9473.29737890222, 9836.311458197857, 9928.404295742279, 10388.74028149272], "value": 7985.374102660789, "unit": "score"}], "name": "dromaeo_css", "value": 7332.947786654655, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 112.0, 114.0, 114.0, 114.0, 115.0, 113.0, 113.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 105.0, 156.0, 107.0, 109.0, 128.0, 103.0, 138.0, 103.0], "value": 120.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 186.0, 181.0, 182.0, 180.0, 182.0, 188.0, 182.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 56.0, 104.0, 57.0, 74.0, 89.0, 78.0, 84.0, 57.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 89.0, 71.0, 78.0, 69.0, 96.0, 69.0, 68.0, 100.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 127.0, 101.0, 85.0, 87.0, 84.0, 88.0, 84.0, 87.0, 83.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 95.0, 94.0, 96.0, 94.0, 88.0, 129.0, 93.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 83.0, 81.0, 87.0, 85.0, 86.0, 85.0, 86.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 53.0, 53.0, 54.0, 53.0, 53.0, 52.0, 54.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 79.0, 78.0, 74.0, 71.0, 72.0, 70.0, 71.0, 74.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 82.0, 83.0, 79.0, 89.0, 100.0, 99.0, 80.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 134.0, 143.0, 150.0, 146.0, 148.0, 135.0, 238.0, 156.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 172.0, 172.0, 169.0, 171.0, 171.0, 170.0, 172.0, 237.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 67.0, 68.0, 69.0, 81.0, 86.0, 84.0], "value": 72.3, "unit": "score"}], "name": "kraken", "value": 1460.6999999999998, "alertThreshold": 2.0}]} 05:32:34 INFO - Return code: 0 05:32:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:32:34 INFO - # TBPL SUCCESS # 05:32:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:32:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:32:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:32:34 INFO - 2016-07-18 05:32:34.859 screenresolution[1662:9758] starting screenresolution argv=screenresolution get 05:32:34 INFO - 2016-07-18 05:32:34.862 screenresolution[1662:9758] Display 0: 1600x1200x32@60 05:32:34 INFO - 2016-07-18 05:32:34.868 screenresolution[1663:9761] starting screenresolution argv=screenresolution list 05:32:34 INFO - Available Modes on Display 0 05:32:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:32:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:32:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:32:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:32:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:32:35 INFO - Intel Iris: 05:32:35 INFO - Chipset Model: Intel Iris 05:32:35 INFO - Type: GPU 05:32:35 INFO - Bus: Built-In 05:32:35 INFO - VRAM (Dynamic, Max): 1536 MB 05:32:35 INFO - Vendor: Intel (0x8086) 05:32:35 INFO - Device ID: 0x0a2e 05:32:35 INFO - Revision ID: 0x0009 05:32:35 INFO - Displays: 05:32:35 INFO - Display: 05:32:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:32:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:32:35 INFO - Display Serial Number: 1600x1200 60 05:32:35 INFO - Main Display: Yes 05:32:35 INFO - Mirror: Off 05:32:35 INFO - Online: Yes 05:32:35 INFO - Rotation: Supported 05:32:35 INFO - Return code: 0 05:32:35 INFO - Running post-action listener: _resource_record_post_action 05:32:35 INFO - ##### 05:32:35 INFO - ##### Finished run-tests step (success) 05:32:35 INFO - ##### 05:32:35 INFO - Running post-run listener: _resource_record_post_run 05:32:35 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32546304; Write bytes: 704482304; Read time: 322; Write time: 1992 05:32:35 INFO - TinderboxPrint: CPU usage
53.5% 05:32:35 INFO - TinderboxPrint: I/O read bytes / time
32,546,304 / 322 05:32:35 INFO - TinderboxPrint: I/O write bytes / time
704,482,304 / 1,992 05:32:35 INFO - TinderboxPrint: CPU idle
2,254.0 (46.5%) 05:32:35 INFO - TinderboxPrint: CPU system
961.9 (19.9%) 05:32:35 INFO - TinderboxPrint: CPU user
1,629.7 (33.6%) 05:32:35 INFO - TinderboxPrint: Swap in / out
435,773,440 / 0 05:32:35 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388411904; Write bytes: 382060544; Read time: 29034; Write time: 741 05:32:35 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31612416; Write bytes: 294440448; Read time: 309; Write time: 1179 05:32:35 INFO - Running post-run listener: _upload_blobber_files 05:32:35 INFO - Blob upload gear active. 05:32:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:32:35 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:32:35 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:32:35 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:32:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:32:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:32:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:32:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:32:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:32:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:32:37 INFO - (blobuploader) - INFO - Done attempting. 05:32:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:32:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:32:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:32:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:32:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:32:38 INFO - (blobuploader) - INFO - Done attempting. 05:32:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:32:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:32:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:32:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:32:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:32:42 INFO - (blobuploader) - INFO - Done attempting. 05:32:42 INFO - (blobuploader) - INFO - Iteration through files over. 05:32:42 INFO - Return code: 0 05:32:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:32:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:32:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/12fb22a79c7feb4abc82a8f519a89446a6a6fa89315b6cd55c221ee0569717ff1f9f99350e4d98f4d8f19b11b52e098c63ec127679a09eb70cbe22391bf87f59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d878744d90a89ee5207fd0941ede774c0e6fbb28ebdd2c5ba1cb82debc31bb124bdc1cb0f48fe4d5417dc161c6d2e69105ca564003f89c5ea4aad6ad6efef1b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/115e65fd0cc6aa6aabed868e433bd7269fbf7d22b79ff432b1ae3edf7b5c1a2d87d742856d903007c6486263c671b0f837cf57682b79fa10315f255ad47fc719"} 05:32:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:32:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:32:42 INFO - Contents: 05:32:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/12fb22a79c7feb4abc82a8f519a89446a6a6fa89315b6cd55c221ee0569717ff1f9f99350e4d98f4d8f19b11b52e098c63ec127679a09eb70cbe22391bf87f59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d878744d90a89ee5207fd0941ede774c0e6fbb28ebdd2c5ba1cb82debc31bb124bdc1cb0f48fe4d5417dc161c6d2e69105ca564003f89c5ea4aad6ad6efef1b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/115e65fd0cc6aa6aabed868e433bd7269fbf7d22b79ff432b1ae3edf7b5c1a2d87d742856d903007c6486263c671b0f837cf57682b79fa10315f255ad47fc719"} 05:32:42 INFO - Running post-run listener: copy_logs_to_upload_dir 05:32:42 INFO - Copying logs to upload dir... 05:32:42 INFO - mkdir: /builds/slave/test/build/upload/logs 05:32:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.657425 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-18 05:32:42.702860) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 05:32:42.708413) ========= 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.4zmrp6oKGE/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.biZ3s2McEW/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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/12fb22a79c7feb4abc82a8f519a89446a6a6fa89315b6cd55c221ee0569717ff1f9f99350e4d98f4d8f19b11b52e098c63ec127679a09eb70cbe22391bf87f59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d878744d90a89ee5207fd0941ede774c0e6fbb28ebdd2c5ba1cb82debc31bb124bdc1cb0f48fe4d5417dc161c6d2e69105ca564003f89c5ea4aad6ad6efef1b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/115e65fd0cc6aa6aabed868e433bd7269fbf7d22b79ff432b1ae3edf7b5c1a2d87d742856d903007c6486263c671b0f837cf57682b79fa10315f255ad47fc719"} build_url:https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011391 build_url: 'https://queue.taskcluster.net/v1/task/CR_HfFEcQMm2JenIb_MYgA/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/12fb22a79c7feb4abc82a8f519a89446a6a6fa89315b6cd55c221ee0569717ff1f9f99350e4d98f4d8f19b11b52e098c63ec127679a09eb70cbe22391bf87f59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d878744d90a89ee5207fd0941ede774c0e6fbb28ebdd2c5ba1cb82debc31bb124bdc1cb0f48fe4d5417dc161c6d2e69105ca564003f89c5ea4aad6ad6efef1b1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/115e65fd0cc6aa6aabed868e433bd7269fbf7d22b79ff432b1ae3edf7b5c1a2d87d742856d903007c6486263c671b0f837cf57682b79fa10315f255ad47fc719"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 05:32:42.741343) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:32:42.741628) ========= 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.4zmrp6oKGE/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.biZ3s2McEW/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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.005136 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:32:42.790358) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 05:32:42.790651) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 05:32:42.790957) ========= ========= Total master_lag: 0.17 =========