builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0335 starttime: 1467295341.37 results: success (0) buildid: 20160630050727 builduid: c4ed06a8a3894842b8e4ff6ac2a8e780 revision: 0996b504bbbdd70b8d9f2055067b61732112139c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:21.374954) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:21.375401) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:21.375677) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:21.400155) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:21.400544) ========= 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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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-06-30 07:02:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 187M=0s 2016-06-30 07:02:21 (187 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092951 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:21.518435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:21.518752) ========= 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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.025572 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:21.566896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-30 07:02:21.570270) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0996b504bbbdd70b8d9f2055067b61732112139c --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 0996b504bbbdd70b8d9f2055067b61732112139c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uRUZaQHVJq/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.TNREl5Vjgw/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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-06-30 07:02:21,657 truncating revision to first 12 chars 2016-06-30 07:02:21,657 Setting DEBUG logging. 2016-06-30 07:02:21,657 attempt 1/10 2016-06-30 07:02:21,657 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0996b504bbbd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-30 07:02:23,368 unpacking tar archive at: fx-team-0996b504bbbd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.982390 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-30 07:02:23.574457) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:23.574898) ========= script_repo_revision: 0996b504bbbdd70b8d9f2055067b61732112139c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:23.575518) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:23.575798) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 07:02:23.594104) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-30 07:02:23.594486) ========= /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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:02:23 INFO - MultiFileLogger online at 20160630 07:02:23 in /builds/slave/test 07:02:23 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 07:02:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:02:23 INFO - {'append_to_log': False, 07:02:23 INFO - 'base_work_dir': '/builds/slave/test', 07:02:23 INFO - 'blob_upload_branch': 'Fx-Team', 07:02:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:02:23 INFO - 'branch': 'Fx-Team', 07:02:23 INFO - 'buildbot_json_path': 'buildprops.json', 07:02:23 INFO - 'config_files': ('talos/mac_config.py',), 07:02:23 INFO - 'default_actions': ('clobber', 07:02:23 INFO - 'read-buildbot-config', 07:02:23 INFO - 'download-and-extract', 07:02:23 INFO - 'populate-webroot', 07:02:23 INFO - 'create-virtualenv', 07:02:23 INFO - 'install', 07:02:23 INFO - 'run-tests'), 07:02:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:02:23 INFO - 'download_minidump_stackwalk': True, 07:02:23 INFO - 'download_symbols': 'ondemand', 07:02:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:02:23 INFO - 'tooltool.py': '/tools/tooltool.py', 07:02:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:02:23 INFO - '/tools/misc-python/virtualenv.py')}, 07:02:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:02:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:02:23 INFO - 'installer_path': 'installer.exe', 07:02:23 INFO - 'log_level': 'info', 07:02:23 INFO - 'log_name': 'talos', 07:02:23 INFO - 'log_to_console': True, 07:02:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:02:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:02:23 INFO - 'opt_config_files': (), 07:02:23 INFO - 'pip_index': False, 07:02:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:02:23 INFO - 'cmd': ('bash', 07:02:23 INFO - '-c', 07:02:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:02:23 INFO - 'enabled': True, 07:02:23 INFO - 'halt_on_failure': False, 07:02:23 INFO - 'name': 'check_screen_resolution'},), 07:02:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:02:23 INFO - 'cmd': ('bash', 07:02:23 INFO - '-c', 07:02:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:02:23 INFO - 'enabled': True, 07:02:23 INFO - 'halt_on_failure': False, 07:02:23 INFO - 'name': 'check_screen_resolution'},), 07:02:23 INFO - 'run_cmd_checks_enabled': True, 07:02:23 INFO - 'sps_profile': False, 07:02:23 INFO - 'sps_profile_interval': 0, 07:02:23 INFO - 'suite': 'dromaeojs', 07:02:23 INFO - 'system_bits': '32', 07:02:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:02:23 INFO - 'title': 't-yosemite-r7-0335', 07:02:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:02:23 INFO - 'use_talos_json': True, 07:02:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:02:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:02:23 INFO - 'work_dir': 'build'} 07:02:23 INFO - ##### 07:02:23 INFO - ##### Running clobber step. 07:02:23 INFO - ##### 07:02:23 INFO - Running pre-action listener: _resource_record_pre_action 07:02:23 INFO - Running main action method: clobber 07:02:23 INFO - rmtree: /builds/slave/test/build 07:02:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:02:25 INFO - Running post-action listener: _resource_record_post_action 07:02:25 INFO - ##### 07:02:25 INFO - ##### Finished clobber step (success) 07:02:25 INFO - ##### 07:02:25 INFO - ##### 07:02:25 INFO - ##### Running read-buildbot-config step. 07:02:25 INFO - ##### 07:02:25 INFO - Running pre-action listener: _resource_record_pre_action 07:02:25 INFO - Running main action method: read_buildbot_config 07:02:25 INFO - Using buildbot properties: 07:02:25 INFO - { 07:02:25 INFO - "project": "", 07:02:25 INFO - "product": "firefox", 07:02:25 INFO - "script_repo_revision": "production", 07:02:25 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:02:25 INFO - "repository": "", 07:02:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:02:25 INFO - "buildid": "20160630050727", 07:02:25 INFO - "pgo_build": "False", 07:02:25 INFO - "basedir": "/builds/slave/test", 07:02:25 INFO - "buildnumber": 74, 07:02:25 INFO - "slavename": "t-yosemite-r7-0335", 07:02:25 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:02:25 INFO - "platform": "yosemite_r7", 07:02:25 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:02:25 INFO - "branch": "fx-team", 07:02:25 INFO - "repo_path": "integration/fx-team", 07:02:25 INFO - "revision": "0996b504bbbdd70b8d9f2055067b61732112139c", 07:02:25 INFO - "stage_platform": "macosx64", 07:02:25 INFO - "builduid": "c4ed06a8a3894842b8e4ff6ac2a8e780", 07:02:25 INFO - "slavebuilddir": "test" 07:02:25 INFO - } 07:02:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 07:02:25 INFO - Running post-action listener: _resource_record_post_action 07:02:25 INFO - ##### 07:02:25 INFO - ##### Finished read-buildbot-config step (success) 07:02:25 INFO - ##### 07:02:25 INFO - ##### 07:02:25 INFO - ##### Running download-and-extract step. 07:02:25 INFO - ##### 07:02:25 INFO - Running pre-action listener: _resource_record_pre_action 07:02:25 INFO - Running main action method: download_and_extract 07:02:25 INFO - mkdir: /builds/slave/test/build/tests 07:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:25 INFO - https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:02:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:02:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 07:02:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/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 07:02:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/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 07:02:26 INFO - Downloaded 1199 bytes. 07:02:26 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 07:02:26 INFO - Using the following test package requirements: 07:02:26 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:02:26 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:02:26 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 07:02:26 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:02:26 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 07:02:26 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:02:26 INFO - u'jsshell-mac.zip'], 07:02:26 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:02:26 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 07:02:26 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 07:02:26 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:02:27 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 07:02:27 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:02:27 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 07:02:27 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:02:27 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 07:02:27 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 07:02:27 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 07:02:27 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:02:27 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')]} 07:02:27 INFO - https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:02:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:02:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:02:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/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 07:02:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/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 07:02:28 INFO - Downloaded 18788664 bytes. 07:02:28 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'] 07:02:28 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 07:02:31 INFO - Return code: 0 07:02:31 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 07:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:31 INFO - https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:02:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:02:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 07:02:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/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 07:02:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/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 07:02:32 INFO - Downloaded 18788664 bytes. 07:02:32 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'] 07:02:32 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 07:02:35 INFO - Return code: 0 07:02: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')]} 07:02:35 INFO - https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:02:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:02:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 07:02:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/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 07:02:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/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 07:02:36 INFO - Downloaded 14405242 bytes. 07:02:36 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'] 07:02:36 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 07:02:37 INFO - Return code: 0 07:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:37 INFO - https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:02:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:02:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:02:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 07:02:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NpMNYwgZS8G35sJHZ44LoA/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 07:02:40 INFO - Downloaded 112404906 bytes. 07:02:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:02:40 INFO - mkdir: /builds/slave/test/properties 07:02:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:02:40 INFO - Writing to file /builds/slave/test/properties/build_url 07:02:40 INFO - Contents: 07:02:40 INFO - build_url:https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 07:02:41 INFO - Running post-action listener: _resource_record_post_action 07:02:41 INFO - Running post-action listener: set_extra_try_arguments 07:02:41 INFO - ##### 07:02:41 INFO - ##### Finished download-and-extract step (success) 07:02:41 INFO - ##### 07:02:41 INFO - ##### 07:02:41 INFO - ##### Running populate-webroot step. 07:02:41 INFO - ##### 07:02:41 INFO - Running pre-action listener: _resource_record_pre_action 07:02:41 INFO - Running main action method: populate_webroot 07:02:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:02:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:02:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:02:41 INFO - '--tppagecycles', 07:02:41 INFO - '7'], 07:02:41 INFO - 'tests': ['tsvgm']}}, 07:02:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:02:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:02:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:02:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:02:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:41 INFO - 'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:02:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:02:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:41 INFO - 'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['damp', 'tps']}, 07:02:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:41 INFO - 'tests': ['damp', 'tps']}, 07:02:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['dromaeo_dom']}, 07:02:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:02:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['basic_compositor_video']}, 07:02:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:02:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['a11yr', 07:02:41 INFO - 'ts_paint', 07:02:41 INFO - 'tpaint', 07:02:41 INFO - 'sessionrestore', 07:02:41 INFO - 'sessionrestore_no_auto_restore', 07:02:41 INFO - 'tabpaint']}, 07:02:41 INFO - 'other-e10s': {'tests': ['a11yr', 07:02:41 INFO - 'ts_paint', 07:02:41 INFO - 'tpaint', 07:02:41 INFO - 'sessionrestore', 07:02:41 INFO - 'sessionrestore_no_auto_restore', 07:02:41 INFO - 'tabpaint']}, 07:02:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:02:41 INFO - 'ts_paint', 07:02:41 INFO - 'tpaint', 07:02:41 INFO - 'sessionrestore', 07:02:41 INFO - 'sessionrestore_no_auto_restore', 07:02:41 INFO - 'tabpaint']}, 07:02:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:02:41 INFO - 'ts_paint', 07:02:41 INFO - 'tpaint', 07:02:41 INFO - 'sessionrestore', 07:02:41 INFO - 'sessionrestore_no_auto_restore', 07:02:41 INFO - 'tabpaint']}, 07:02:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['a11yr', 07:02:41 INFO - 'ts_paint', 07:02:41 INFO - 'tpaint', 07:02:41 INFO - 'sessionrestore', 07:02:41 INFO - 'sessionrestore_no_auto_restore', 07:02:41 INFO - 'tabpaint']}, 07:02:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['a11yr', 07:02:41 INFO - 'ts_paint', 07:02:41 INFO - 'tpaint', 07:02:41 INFO - 'sessionrestore', 07:02:41 INFO - 'sessionrestore_no_auto_restore', 07:02:41 INFO - 'tabpaint']}, 07:02:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['tsvgx', 07:02:41 INFO - 'tsvgr_opacity', 07:02:41 INFO - 'tart', 07:02:41 INFO - 'tscrollx', 07:02:41 INFO - 'cart']}, 07:02:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:02:41 INFO - 'tsvgr_opacity', 07:02:41 INFO - 'tart', 07:02:41 INFO - 'tscrollx', 07:02:41 INFO - 'cart']}, 07:02:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:41 INFO - 'talos_options': ['--disable-e10s'], 07:02:41 INFO - 'tests': ['tp5o']}, 07:02:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:41 INFO - 'tests': ['tp5o']}, 07:02:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:41 INFO - 'talos_options': ['--disable-e10s', 07:02:41 INFO - '--xperf_path', 07:02:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:02:41 INFO - 'tests': ['tp5n']}, 07:02:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:41 INFO - 'talos_options': ['--xperf_path', 07:02:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:02:41 INFO - 'tests': ['tp5n']}}, 07:02:41 INFO - 'talos.zip': {'path': '', 07:02:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:02:41 INFO - Running post-action listener: _resource_record_post_action 07:02:41 INFO - ##### 07:02:41 INFO - ##### Finished populate-webroot step (success) 07:02:41 INFO - ##### 07:02:41 INFO - ##### 07:02:41 INFO - ##### Running create-virtualenv step. 07:02:41 INFO - ##### 07:02:41 INFO - Running pre-action listener: _resource_record_pre_action 07:02:41 INFO - Running main action method: create_virtualenv 07:02:41 INFO - Creating virtualenv /builds/slave/test/build/venv 07:02:41 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 07:02:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:02:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:02:41 INFO - Using real prefix '/tools/python27' 07:02:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:02:42 INFO - Installing distribute.............................................................................................................................................................................................done. 07:02:44 INFO - Installing pip.................done. 07:02:44 INFO - Return code: 0 07:02:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:02:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106190a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106225618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd838d6e740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, '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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:02:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:02:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:02:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:02:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:44 INFO - 'HOME': '/Users/cltbld', 07:02:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:44 INFO - 'LOGNAME': 'cltbld', 07:02:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:44 INFO - 'MOZ_NO_REMOTE': '1', 07:02:44 INFO - 'NO_EM_RESTART': '1', 07:02:44 INFO - 'PAGER': '/bin/cat', 07:02:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:44 INFO - 'PWD': '/builds/slave/test', 07:02:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:44 INFO - 'SHELL': '/bin/bash', 07:02:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:02:44 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:02:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:44 INFO - 'USER': 'cltbld', 07:02:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:44 INFO - 'XPC_FLAGS': '0x0', 07:02:44 INFO - 'XPC_SERVICE_NAME': '0', 07:02:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:45 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 07:02:45 INFO - Cleaning up... 07:02:45 INFO - Return code: 0 07:02:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:02:45 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')]} 07:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:45 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')]} 07:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:45 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 0x106190a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106225618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd838d6e740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, '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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:02:45 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 07:02:45 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 07:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:45 INFO - 'HOME': '/Users/cltbld', 07:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:45 INFO - 'LOGNAME': 'cltbld', 07:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:45 INFO - 'MOZ_NO_REMOTE': '1', 07:02:45 INFO - 'NO_EM_RESTART': '1', 07:02:45 INFO - 'PAGER': '/bin/cat', 07:02:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:45 INFO - 'PWD': '/builds/slave/test', 07:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:45 INFO - 'SHELL': '/bin/bash', 07:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:02:45 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:45 INFO - 'USER': 'cltbld', 07:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:45 INFO - 'XPC_FLAGS': '0x0', 07:02:45 INFO - 'XPC_SERVICE_NAME': '0', 07:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:45 INFO - Downloading/unpacking psutil>=3.1.1 07:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:02:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:02:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:02:48 INFO - Installing collected packages: psutil 07:02:48 INFO - Running setup.py install for psutil 07:02:48 INFO - building 'psutil._psutil_osx' extension 07:02:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:02:49 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 07:02:49 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 07:02:49 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 07:02:49 INFO - building 'psutil._psutil_posix' extension 07:02:49 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 07:02:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:02:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:02:49 INFO - ^ 07:02:49 INFO - 1 warning generated. 07:02:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:02:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:02:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:02:49 INFO - Successfully installed psutil 07:02:49 INFO - Cleaning up... 07:02:49 INFO - Return code: 0 07:02:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106190a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106225618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd838d6e740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, '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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:02:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:02:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:02:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:02:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:49 INFO - 'HOME': '/Users/cltbld', 07:02:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:49 INFO - 'LOGNAME': 'cltbld', 07:02:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:49 INFO - 'MOZ_NO_REMOTE': '1', 07:02:49 INFO - 'NO_EM_RESTART': '1', 07:02:49 INFO - 'PAGER': '/bin/cat', 07:02:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:49 INFO - 'PWD': '/builds/slave/test', 07:02:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:49 INFO - 'SHELL': '/bin/bash', 07:02:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:02:49 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:02:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:49 INFO - 'USER': 'cltbld', 07:02:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:49 INFO - 'XPC_FLAGS': '0x0', 07:02:49 INFO - 'XPC_SERVICE_NAME': '0', 07:02:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:02:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:02:52 INFO - Installing collected packages: mozsystemmonitor 07:02:52 INFO - Running setup.py install for mozsystemmonitor 07:02:52 INFO - Successfully installed mozsystemmonitor 07:02:52 INFO - Cleaning up... 07:02:52 INFO - Return code: 0 07:02:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106190a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106225618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd838d6e740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, '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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:02:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:02:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:52 INFO - 'HOME': '/Users/cltbld', 07:02:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:52 INFO - 'LOGNAME': 'cltbld', 07:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:52 INFO - 'MOZ_NO_REMOTE': '1', 07:02:52 INFO - 'NO_EM_RESTART': '1', 07:02:52 INFO - 'PAGER': '/bin/cat', 07:02:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:52 INFO - 'PWD': '/builds/slave/test', 07:02:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:52 INFO - 'SHELL': '/bin/bash', 07:02:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:02:52 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:02:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:52 INFO - 'USER': 'cltbld', 07:02:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:52 INFO - 'XPC_FLAGS': '0x0', 07:02:52 INFO - 'XPC_SERVICE_NAME': '0', 07:02:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:52 INFO - Downloading/unpacking jsonschema==2.5.1 07:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:02:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:55 INFO - Downloading functools32-3.2.3-2.tar.gz 07:02:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:02:55 INFO - warning: no files found matching '*.txt' 07:02:55 INFO - no previously-included directories found matching 'build' 07:02:55 INFO - no previously-included directories found matching 'dist' 07:02:55 INFO - no previously-included directories found matching '.git*' 07:02:55 INFO - Installing collected packages: jsonschema, functools32 07:02:55 INFO - Running setup.py install for functools32 07:02:56 INFO - warning: no files found matching '*.txt' 07:02:56 INFO - no previously-included directories found matching 'build' 07:02:56 INFO - no previously-included directories found matching 'dist' 07:02:56 INFO - no previously-included directories found matching '.git*' 07:02:56 INFO - Successfully installed jsonschema functools32 07:02:56 INFO - Cleaning up... 07:02:56 INFO - Return code: 0 07:02:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:02: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')]} 07:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02: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')]} 07:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02: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', '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 0x106190a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106225618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd838d6e740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, '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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:02: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:02: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 blobuploader==1.2.4 07:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:56 INFO - 'HOME': '/Users/cltbld', 07:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:56 INFO - 'LOGNAME': 'cltbld', 07:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:56 INFO - 'MOZ_NO_REMOTE': '1', 07:02:56 INFO - 'NO_EM_RESTART': '1', 07:02:56 INFO - 'PAGER': '/bin/cat', 07:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:56 INFO - 'PWD': '/builds/slave/test', 07:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:56 INFO - 'SHELL': '/bin/bash', 07:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:02:56 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:56 INFO - 'USER': 'cltbld', 07:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:56 INFO - 'XPC_FLAGS': '0x0', 07:02:56 INFO - 'XPC_SERVICE_NAME': '0', 07:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:56 INFO - Downloading/unpacking blobuploader==1.2.4 07:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:58 INFO - Downloading blobuploader-1.2.4.tar.gz 07:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:02:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:02:59 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 07:02:59 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 07:02:59 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 07:02:59 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 07:02:59 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 07:02:59 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 07:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:02:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:02:59 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 07:02:59 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 07:02:59 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 07:02:59 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 07:02:59 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 07:02:59 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 07:03:00 INFO - Downloading docopt-0.6.1.tar.gz 07:03:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:03:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:03:00 INFO - Running setup.py install for blobuploader 07:03:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:03:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:03:00 INFO - Running setup.py install for requests 07:03:00 INFO - Running setup.py install for docopt 07:03:00 INFO - Successfully installed blobuploader requests docopt 07:03:00 INFO - Cleaning up... 07:03:01 INFO - Return code: 0 07:03:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:03: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')]} 07:03:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03: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')]} 07:03:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:01 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 0x106190a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106225618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd838d6e740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, '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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:03:01 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 07:03:01 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 07:03:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:03:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:01 INFO - 'HOME': '/Users/cltbld', 07:03:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:01 INFO - 'LOGNAME': 'cltbld', 07:03:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:01 INFO - 'MOZ_NO_REMOTE': '1', 07:03:01 INFO - 'NO_EM_RESTART': '1', 07:03:01 INFO - 'PAGER': '/bin/cat', 07:03:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:01 INFO - 'PWD': '/builds/slave/test', 07:03:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:01 INFO - 'SHELL': '/bin/bash', 07:03:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:03:01 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:03:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:01 INFO - 'USER': 'cltbld', 07:03:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:01 INFO - 'XPC_FLAGS': '0x0', 07:03:01 INFO - 'XPC_SERVICE_NAME': '0', 07:03:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:03:01 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-dlPVOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:03:01 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-PceTtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:03:01 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-sgAScg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:03:01 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-0kyyB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:03:01 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-HM3y3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:03:01 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-IlQ3Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:03:01 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-hucX7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:03:02 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-GYbQ7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:03:02 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-BbDY4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:03:02 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Pk9ibW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:03:02 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-kbc9J6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:03:02 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Pn5LQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:03:02 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-bk7FK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:03:02 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-JfZxUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:02 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-p57WWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:03:02 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-tf3dXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:03:03 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-f9RlAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:03:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:03:03 INFO - Running setup.py install for manifestparser 07:03:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:03:03 INFO - Running setup.py install for mozcrash 07:03:03 INFO - Running setup.py install for mozdebug 07:03:03 INFO - Running setup.py install for mozdevice 07:03:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:03:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:03:03 INFO - Running setup.py install for mozfile 07:03:03 INFO - Running setup.py install for mozhttpd 07:03:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:03:03 INFO - Running setup.py install for mozinfo 07:03:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:03:04 INFO - Running setup.py install for mozInstall 07:03:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:03:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:03:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:03:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:03:04 INFO - Running setup.py install for mozleak 07:03:04 INFO - Running setup.py install for mozlog 07:03:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:03:04 INFO - Running setup.py install for moznetwork 07:03:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:03:04 INFO - Running setup.py install for mozprocess 07:03:04 INFO - Running setup.py install for mozprofile 07:03:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:03:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:03:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:03:05 INFO - Running setup.py install for mozrunner 07:03:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:03:05 INFO - Running setup.py install for mozscreenshot 07:03:05 INFO - Running setup.py install for moztest 07:03:05 INFO - Running setup.py install for mozversion 07:03:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:03:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:03:05 INFO - Cleaning up... 07:03:05 INFO - Return code: 0 07:03:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:03:05 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')]} 07:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:05 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')]} 07:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:05 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 0x106190a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106225618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd838d6e740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, '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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:03:05 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 07:03:05 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 07:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:05 INFO - 'HOME': '/Users/cltbld', 07:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:05 INFO - 'LOGNAME': 'cltbld', 07:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:05 INFO - 'MOZ_NO_REMOTE': '1', 07:03:05 INFO - 'NO_EM_RESTART': '1', 07:03:05 INFO - 'PAGER': '/bin/cat', 07:03:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:05 INFO - 'PWD': '/builds/slave/test', 07:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:05 INFO - 'SHELL': '/bin/bash', 07:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:03:05 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:05 INFO - 'USER': 'cltbld', 07:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:05 INFO - 'XPC_FLAGS': '0x0', 07:03:05 INFO - 'XPC_SERVICE_NAME': '0', 07:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:03:05 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-7ypWj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:03:06 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)) 07:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:03:06 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-dQrbLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:03:06 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)) 07:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:03:06 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Z6TCCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:03:06 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)) 07:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:03:06 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-yy2h_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:03:06 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)) 07:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:03:06 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-oIyzR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:03:06 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)) 07:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:03:06 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-WWkx49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:03:06 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)) 07:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:03:06 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-wWrSNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:03:06 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)) 07:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:03:06 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-vZf0es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:03:06 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)) 07:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:03:06 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-3uoCKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:03:06 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)) 07:03:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:03:06 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-X6z2Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:03:07 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)) 07:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:03:07 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-jS294O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:03:07 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)) 07:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:03:07 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-6UMYLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:03:07 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-aN2Bk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:03:07 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)) 07:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:03:07 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-8LNUDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:07 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-94PYLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:03:07 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)) 07:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:03:07 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-d5oOsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:03:07 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)) 07:03:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:03:07 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-2KiOD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:03:07 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)) 07:03:07 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)) 07:03:07 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)) 07:03:07 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)) 07:03:07 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)) 07:03:07 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)) 07:03:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:03: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 07:03: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 07:03: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 07:03: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 07:03: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 07:03: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 07:03:10 INFO - Downloading blessings-1.6.tar.gz 07:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:03:10 INFO - Installing collected packages: blessings 07:03:10 INFO - Running setup.py install for blessings 07:03:10 INFO - Successfully installed blessings 07:03:10 INFO - Cleaning up... 07:03:10 INFO - Return code: 0 07:03:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:03:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:03:10 INFO - Reading from file tmpfile_stdout 07:03:10 INFO - Current package versions: 07:03:10 INFO - blessings == 1.6 07:03:10 INFO - blobuploader == 1.2.4 07:03:10 INFO - docopt == 0.6.1 07:03:10 INFO - functools32 == 3.2.3-2 07:03:10 INFO - jsonschema == 2.5.1 07:03:10 INFO - manifestparser == 1.1 07:03:10 INFO - mozInstall == 1.12 07:03:10 INFO - mozcrash == 0.17 07:03:10 INFO - mozdebug == 0.1 07:03:10 INFO - mozdevice == 0.48 07:03:10 INFO - mozfile == 1.2 07:03:10 INFO - mozhttpd == 0.7 07:03:10 INFO - mozinfo == 0.9 07:03:10 INFO - mozleak == 0.1 07:03:10 INFO - mozlog == 3.2 07:03:10 INFO - moznetwork == 0.27 07:03:10 INFO - mozprocess == 0.22 07:03:10 INFO - mozprofile == 0.28 07:03:10 INFO - mozrunner == 6.11 07:03:10 INFO - mozscreenshot == 0.1 07:03:10 INFO - mozsystemmonitor == 0.3 07:03:10 INFO - moztest == 0.7 07:03:10 INFO - mozversion == 1.4 07:03:10 INFO - psutil == 3.1.1 07:03:10 INFO - requests == 1.2.3 07:03:10 INFO - wsgiref == 0.1.2 07:03:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:03:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:10 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 0x106190a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106225618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd838d6e740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, '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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:03:10 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 07:03:10 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 07:03:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:03:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:10 INFO - 'HOME': '/Users/cltbld', 07:03:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:10 INFO - 'LOGNAME': 'cltbld', 07:03:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:10 INFO - 'MOZ_NO_REMOTE': '1', 07:03:10 INFO - 'NO_EM_RESTART': '1', 07:03:10 INFO - 'PAGER': '/bin/cat', 07:03:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:10 INFO - 'PWD': '/builds/slave/test', 07:03:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:10 INFO - 'SHELL': '/bin/bash', 07:03:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:03:10 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:03:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:10 INFO - 'USER': 'cltbld', 07:03:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:10 INFO - 'XPC_FLAGS': '0x0', 07:03:10 INFO - 'XPC_SERVICE_NAME': '0', 07:03:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:11 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)) 07:03:11 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)) 07:03:11 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)) 07:03:11 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)) 07:03:11 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)) 07:03:11 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)) 07:03:11 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)) 07:03:11 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)) 07:03:11 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)) 07:03:11 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)) 07:03:11 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)) 07:03:11 INFO - Cleaning up... 07:03:11 INFO - Return code: 0 07:03:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:03:11 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')]} 07:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:11 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')]} 07:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:03:11 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 0x106190a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106225618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd838d6e740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, '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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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 07:03:11 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 07:03:11 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 07:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:11 INFO - 'HOME': '/Users/cltbld', 07:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:11 INFO - 'LOGNAME': 'cltbld', 07:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:11 INFO - 'MOZ_NO_REMOTE': '1', 07:03:11 INFO - 'NO_EM_RESTART': '1', 07:03:11 INFO - 'PAGER': '/bin/cat', 07:03:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:11 INFO - 'PWD': '/builds/slave/test', 07:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:11 INFO - 'SHELL': '/bin/bash', 07:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:03:11 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:11 INFO - 'USER': 'cltbld', 07:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:11 INFO - 'XPC_FLAGS': '0x0', 07:03:11 INFO - 'XPC_SERVICE_NAME': '0', 07:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:03:11 INFO - Cleaning up... 07:03:11 INFO - Return code: 0 07:03:11 INFO - Running post-action listener: _resource_record_post_action 07:03:11 INFO - Running post-action listener: _start_resource_monitoring 07:03:11 INFO - Starting resource monitoring. 07:03:11 INFO - ##### 07:03:11 INFO - ##### Finished create-virtualenv step (success) 07:03:11 INFO - ##### 07:03:11 INFO - ##### 07:03:11 INFO - ##### Running install step. 07:03:11 INFO - ##### 07:03:11 INFO - Running pre-action listener: _resource_record_pre_action 07:03:11 INFO - Running main action method: install 07:03:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:03:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:03:11 INFO - Reading from file tmpfile_stdout 07:03:11 INFO - Detecting whether we're running mozinstall >=1.0... 07:03:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:03:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:03:11 INFO - Reading from file tmpfile_stdout 07:03:11 INFO - Output received: 07:03:11 INFO - Usage: mozinstall [options] installer 07:03:11 INFO - Options: 07:03:11 INFO - -h, --help show this help message and exit 07:03:11 INFO - -d DEST, --destination=DEST 07:03:11 INFO - Directory to install application into. [default: 07:03:11 INFO - "/builds/slave/test"] 07:03:11 INFO - --app=APP Application being installed. [default: firefox] 07:03:11 INFO - mkdir: /builds/slave/test/build/application 07:03:11 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'] 07:03:11 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 07:03:45 INFO - Reading from file tmpfile_stdout 07:03:45 INFO - Output received: 07:03:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:03:45 INFO - Running post-action listener: _resource_record_post_action 07:03:45 INFO - ##### 07:03:45 INFO - ##### Finished install step (success) 07:03:45 INFO - ##### 07:03:45 INFO - ##### 07:03:45 INFO - ##### Running run-tests step. 07:03:45 INFO - ##### 07:03:45 INFO - Running pre-action listener: _resource_record_pre_action 07:03:45 INFO - Running main action method: run_tests 07:03:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:03:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:03:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:03:45 INFO - 2016-06-30 07:03:45.970 screenresolution[1640:7528] starting screenresolution argv=screenresolution get 07:03:45 INFO - 2016-06-30 07:03:45.972 screenresolution[1640:7528] Display 0: 1600x1200x32@60 07:03:45 INFO - 2016-06-30 07:03:45.978 screenresolution[1641:7530] starting screenresolution argv=screenresolution list 07:03:45 INFO - Available Modes on Display 0 07:03:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:03:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:03:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:03:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:03:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:03:46 INFO - Intel Iris: 07:03:46 INFO - Chipset Model: Intel Iris 07:03:46 INFO - Type: GPU 07:03:46 INFO - Bus: Built-In 07:03:46 INFO - VRAM (Dynamic, Max): 1536 MB 07:03:46 INFO - Vendor: Intel (0x8086) 07:03:46 INFO - Device ID: 0x0a2e 07:03:46 INFO - Revision ID: 0x0009 07:03:46 INFO - Displays: 07:03:46 INFO - Display: 07:03:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:03:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:03:46 INFO - Display Serial Number: 1600x1200 60 07:03:46 INFO - Main Display: Yes 07:03:46 INFO - Mirror: Off 07:03:46 INFO - Online: Yes 07:03:46 INFO - Rotation: Supported 07:03:46 INFO - Return code: 0 07:03:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:03:46 INFO - Python 2.7.3 07:03:46 INFO - Return code: 0 07:03:46 INFO - grabbing minidump binary from tooltool 07:03: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')]} 07:03:46 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 0x1060f4d30>, '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 0x105e59db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060ecb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:03:46 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 07:03:46 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 07:03:46 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 07:03:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:03:46 INFO - Return code: 0 07:03:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:03:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:03:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:03:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:03:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:03:46 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/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--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 07:03:46 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/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0335 --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 07:03:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uRUZaQHVJq/Render', 07:03:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:46 INFO - 'HOME': '/Users/cltbld', 07:03:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:46 INFO - 'LOGNAME': 'cltbld', 07:03:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:03:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:46 INFO - 'MOZ_NO_REMOTE': '1', 07:03:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:03:46 INFO - 'NO_EM_RESTART': '1', 07:03:46 INFO - 'PAGER': '/bin/cat', 07:03:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:46 INFO - 'PWD': '/builds/slave/test', 07:03:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:03:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:46 INFO - 'SHELL': '/bin/bash', 07:03:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TNREl5Vjgw/Listeners', 07:03:46 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 07:03:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:46 INFO - 'USER': 'cltbld', 07:03:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:46 INFO - 'XPC_FLAGS': '0x0', 07:03:46 INFO - 'XPC_SERVICE_NAME': '0', 07:03:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:46 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/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--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 07:03:46 INFO - mozversion application_buildid: 20160630050727 07:03:46 INFO - mozversion application_changeset: 0996b504bbbdd70b8d9f2055067b61732112139c 07:03:46 INFO - mozversion application_display_name: Nightly 07:03:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:03:46 INFO - mozversion application_name: Firefox 07:03:46 INFO - mozversion application_remotingname: firefox 07:03:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:03:46 INFO - mozversion application_vendor: Mozilla 07:03:46 INFO - mozversion application_version: 50.0a1 07:03:46 INFO - mozversion platform_buildid: 20160630050727 07:03:46 INFO - mozversion platform_changeset: 0996b504bbbdd70b8d9f2055067b61732112139c 07:03:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:03:46 INFO - mozversion platform_version: 50.0a1 07:03:46 INFO - using testdate: 1467295426 07:03:46 INFO - actual date: 1467295426 07:03:46 INFO - starting webserver on 'localhost:49271' 07:03:46 INFO - SUITE-START | Running 2 tests 07:03:46 INFO - TEST-START | dromaeo_css 07:03:46 INFO - operating with platform_type : mac_ 07:03:46 INFO - Initialising browser for dromaeo_css test... 07:03:46 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmp5NR6DD/profile http://localhost:49271/getInfo.html) 07:03:49 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 07:03:49 INFO - PROCESS | 1651 | colorDepth:24 07:03:49 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 07:03:49 INFO - PROCESS | 1651 | __metrics 07:03:49 INFO - PROCESS | 1651 | 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" 07:03:49 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:03:50 INFO - TEST-INFO | 1651: exit 0 07:03:50 INFO - Browser initialized. 07:03:50 INFO - Running cycle 1/1 for dromaeo_css test... 07:03:50 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmp5NR6DD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:04:02 INFO - PROCESS | 1653 | RSS: Main: 243777536 07:04:02 INFO - PROCESS | 1653 | 07:04:53 INFO - PROCESS | 1653 | 1467295493774 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] 07:07:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 07:07:05 INFO - PROCESS | 1653 | RSS: Main: 363122688 07:07:05 INFO - PROCESS | 1653 | 07:10:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 07:10:09 INFO - PROCESS | 1653 | RSS: Main: 368807936 07:10:09 INFO - PROCESS | 1653 | 07:13:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 07:13:12 INFO - PROCESS | 1653 | RSS: Main: 370462720 07:13:12 INFO - PROCESS | 1653 | 07:16:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 07:16:15 INFO - PROCESS | 1653 | RSS: Main: 371744768 07:16:15 INFO - PROCESS | 1653 | 07:19:18 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 07:19:19 INFO - PROCESS | 1653 | RSS: Main: 344092672 07:19:19 INFO - PROCESS | 1653 | 07:22:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 07:22:23 INFO - PROCESS | 1653 | RSS: Main: 381472768 07:22:23 INFO - PROCESS | 1653 | 07:22:23 INFO - PROCESS | 1653 | __start_tp_report 07:22:23 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 07:22:23 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 07:22:23 INFO - PROCESS | 1653 | |i|pagename|runs| 07:22:23 INFO - PROCESS | 1653 | |0;dojo.html;3331.3170799986;3331.600207975042;3370.2080011197363;3380.8309584520766;3418.641042690517;5546.750396232169;5576.721163941802;5607.607467477278;5658.943410565893;5676.659400435971;5605.803796867111;5619.438056194368;5737.196792449061;5749.712514374285;5793;5077.41609714882;5125.564327032188;5159.638825282232;5184.585233181337;5267.3942496613;5152.484751524855;5286.392064912546;5288.6562373445795;5294.391145018334;5335.1730481775385;5069.163588007974;5150.381954165487;5164.225366195101;5188.04021256067;5221.321228240305;5117.437081921023;5185.325907632021;5189.195674670432;5192.92210616841;5213.791448342062;5135.897282054375;5147.305113809625;5188.688678679254;5220.504052115043;5257;5668.31980162379;5674.347450043257;5689.800856969986;5758.6544807311275;5770.134479828017;5899.085641725539;6136.274005700925;6199.473044791153;6203.131561581382;6203.348648391944;6172.969135154341;6243.219597550306;6244.531660125509;6256.343083976208;6258.4680302174365;5518.503334699896;5750.769969201185;5758.107493338539;5764;5778.8266352009505;6160.260768707694;6215.96892015537;6284.5600807943;6285.9057114143325;6331.240251169889;5918.378570250148;5972.790952316735;6079.969600151971;6138.508919286446;6152.938470615326;5944.613600115978;6100.938990610126;6231.376862313732;6249.96875015622;6286.088517164985;6029.216201893813;6062.636241825505;6115.327313995457;6135.385782596134;6217.316095229521;6145.845290797189;6236.875262494725;6240.251169859555;6242.906356404657;6266.310705822356;4842.539958703917;4893.510648935078;4931.679440836334;4975.378077740283;5010.2735103409805;4377.0808130293;4403.8238470461465;4412.536683648235;4414.161309351213;4433.179861725591;5628.465295796893;5690.715464226772;5696.857578560569;5716.1997320375185;5724.685142317129;6109.75560977556;6119.563485741553;6235.469985051322;6295.716692748837;6297.685115744194;5595;5650.26546548946;5663;5674.148877668383;5708.743106560215;5411.458854114557;5432;5442;5447.782088716407;5452.59105567084;6230;6297.023961286008;6334.683265836782;6411.166548348685;6458.289588145394;6070.2108725865355;6087.9391206087375;6101.450869421773;6173.876522469435;6258.998560230341;6302.653354065387;6314.1160237565855;6361.236651601837;6377.649229292433;6388.744450221939;5860.941390586039;5924.496417804535;6114.113453549299;6128.9693551531955;6140.048292514667;6157.953147964767;6191.504679625529;6275.435210831047;6295.874082518416;6316.021016742412;6074.544409169242;6083.69581520931;6103.206583144163;6103.328633850184;6111.96944015277;5370.516653501204;5737.225474560881;5897.67562784051;5973.850653733692;6011.579189456871;5839.065749480063;5884.08796636522;5898.8230353089475;5902.409759024235;5943.881122377442;6040.2147720797775;6067.787627433196;6076.605020673642;6084.543659225665;6088.878222435438;5682.829515114553;5762.798302059407;5778.739956702043;5819.883602327845;5898.675572843535 07:22:23 INFO - PROCESS | 1653 | |1;ext.html;490.8870959679274;493.5336107378529;497.1076916934103;516.9922451163236;526.0321009342808;31459.370812583733;41788;42700;42934.57065429345;45468.54531454684;43759;44066.779666101706;44168.116637667226;44277.7786111069;44341.891452713695;8790.824183516326;9694.418334899929;9887.555060022316;9904.257180711475;9904.405735655848;8738.432001919886;8974.28205743539;8981.506017169053;8987.640494380217;12025.518979240864;10424.113950314195;11931.403429828517;12139.271643701406;12238.38808059598;12274.815877761795;12104.971077458427;12167.695807604792;12175.45210465531;12177.573784917575;12182.695432614168;8705.173008564176;8915.910840891573;8916.420432671855;9034.86447703285;11942.343171125585;41149.38275925864;41645.79177104125;41653.791731041456;43922;44181;3395.185155562647;3726.757760745543;4134.834606615732;4179.582041795827;4323.027318853233;33121.83439082813;33222.6677733222;33246.83376583126;33274.500882486784;33425.49861752076;3207.839608019613;3429.6227414984332;3474.096734848932;3478.147853775841;3481.895543133685;373774;374508;374677;376865;382812.9999999972;52872;54162.72918635382;54323.18515222275;63885.04172437417;64527.35472645213;67668;67827.66086169537;67828.6608566954;67889.66055169693;67978;15200.847991520164;16891.15544222784;17139.400120995706;17177.570560735832;17180.828191718174;11758.470868810924;12047;12058;12065.758684826429;12095.334756588472;7724.0731112266885;7794.181610930884;14715.789394627895;18351.449456516326;19234.326798561982;6771.864562708718;7821.045886052373;7978.880316795253;8059.073206581201;8092.635831387602;883.5626364949392;964.6671898195111;1001.234055947201;1064.1114669251108;1080.9945950270198;25486.490270194492;30636.387272254877;31298;32068.358632827214;32348.029559113264;1027.1372047480152;1029.1715169288645;1052.6157952347337;1069.2034434346463;1111.0833562311125;14039.157650541;16116;16460.588485287724;16502.33990640361;16645.83354166467;697672;701127;708031;708755;710078;62695.6865215671;63386.73226535536;63404.68297658482;63472.04791928125;63515;1245.9813102803466;1278.6611547939804;1345.9125156864773;1361.1424802374638;1388.1324172392256;30666.233344166576;32813.67186328202;32861.67138328682;33294.07262733635;33402.49896251607;52729.736351317995;53645.195322070984;53713.19430208472;53745.73127134339;53879;493609;506806;517248;517324;522267;22986.885065574566;24460.65466399365;27305.269473052536;27906.16281511558;27990.160295190362;48867.26699099445;50240;51173;51544;51648.708782280744;38831.41752873653;41456.79271603622;42226.788866055475;42467;42563;39646.60353396429;41047.794761026;41260.79369603133;41382;42368.15263694647;39734.60265397309;39789.806305810875;40430;40614.1877162461;40695.1860962785;33157.83421082879;35087;35807;36024;36059;22622.660660089783;25679.229623111336;25806.8709656458;26088.34779130537;26673.19980400591 07:22:23 INFO - PROCESS | 1653 | |2;jquery.html;363.1719679131581;369.44398679986625;388.06282826972864;393.3333000564043;401.96181362770545;15695.52913412598;16444.095574743384;20684.379468615967;21206.833624150666;21239.15043398266;21342.932853357346;21581.67627485589;21619.3514194574;21665.56668866622;21674.91625418727;5909.970450147754;5914.319853216871;6082.695865206744;6141.846453838645;6187.164732761087;6058.787942422016;6066.514678825683;6086.269647642267;6100.389961003886;6121.540884433663;5733.627314224583;6017.458428741413;6020.488258498033;6031.60794548353;6067.666278354689;5667.4049224831215;5927.081302398135;5937.495312898387;5963.105534169866;5972.014617588115;5169.457206993249;5184.818531351385;5214.713190774506;5216.478352164791;5232.502912223333;26510;26514.337141571614;26528.73471265282;26556;26895.596566051525;2215.435064058655;2267.671187677794;2366.7514910934447;2386.045581767307;2513.673222481084;15151.545453636409;15729.606759831096;15922.76115858263;16306.510804675878;16475.752863707054;1859.8512119030581;1877.2584828992472;1897.0704354866155;1907.160849226336;1929.778075521319;88576.11423885744;106907;107379;107791.46104269428;108808.4559577205;10829.566817327219;10955.233133680566;11281.548738050386;11316.660500185008;11448.713782155346;13946.86053139476;14058.437662493387;14059.578212653636;14097.647558811112;14108.2945852709;8569.442986205877;8773.649054037895;8828.293736501064;8858.025617182106;8885.333599980027;7152.749653762094;8754.69358572447;8790.736277911672;8928.866067009;8954.417962832395;6751.459883209332;6900.5859648420255;6906.585604863724;6918.481113916477;6924.3421874921805;1856.6193930244267;1897.8743806936043;1898.6012937283326;1898.943031709051;1901.3820508334848;896.9550473698068;913.0823522359985;921.3826736086838;981.9607215711292;1036.6371769880482;21853.890730546562;22636.320910372717;23036.4240893979;23068;23079.307620771404;878.3017501086651;914.2503147419053;965.5992763003275;994.16986816008;1049.2707568240153;11889;12041.217320874115;12215.755684886253;12238.449269782881;12254.509819607116;581344;588210;596102;600739;602672;66062.33937660563;86492;96445.51777241209;106765.57181203474;116616;947.2422062350092;975.6829030565095;979.5738853598707;1011.5195282240877;1012.8733908261468;17258.913705431394;19658.8204707718;19912.800871991672;20377.69433458529;20583.38249852507;51852;52140.95718085541;52363.47636523738;52970.73514632402;53702;68379.65810171116;68875.65562172157;69770.65114674394;69833.30166698268;71227.21931951743;6959.338862808025;7310.159331676925;7982;8035.156308587514;8252.917470825216;11978.58074967372;12846.164999275017;12970.675733106747;13063;13214.405351759002;9564.330496865152;11213.046891014355;11309.095272378354;11310.94344528305;11584.768304633692;9711.951440242754;11075.446227688422;11256.437178140897;11265.380404077527;11413.429328533706;11296.943515282372;11386.146039046938;11443.885561144614;11638.185327027028;11647;6434.8391290218115;7339.669714862737;7422.109346878409;7460.738874139379;7461.328480436787;8298.543580103153;11093.22347435704;11462.828057578976;11484.310941343547;11675.649730508097 07:22:23 INFO - PROCESS | 1653 | |3;mootools.html;1524.0703171065652;1554.8445155484449;1741.3556983915953;1881.5390229393784;1891.9905400472996;8491.872621910676;8784.648614055446;8978.416402933804;8980.910190898088;9008.639654413824;7827.960860195705;8087.433879628428;8576.785580360494;8756;8821.382503224777;2370.786629203367;2398.688170537828;2399.196269249803;2401.4836810085826;2404.1345115758313;2360.504294098233;2369.8104151667826;2408;2410.409449684821;2413.4087148648605;2109.6940943563172;2197.7692342304067;2310.07596961216;2388.976110238893;2388.976110238893;1980.9306674266475;2174.325958952722;2236.8769717665523;2366.751491093428;2406.6871306730186;2357.9410514737096;2380.238323736405;2382.6187809950325;2383.368407372048;2397.460571371445;8922.910770892338;9038.909610903873;9057.728268151966;9068.138526839939;9092.363534552584;5279.63042587019;5436.293281873332;5450.427705090988;5450.890982180374;5457.699826509541;3579.1410061585293;3590.4793804898404;3642.1987162824134;3644.9271014579826;3653.0684675407865;1067.8024565455337;1076.262760009396;1085.3704851186294;1097.237419993105;1099.1811100729929;11949.402529873558;11951.402429878472;11954.46204920772;11961.581344652895;11970.58102966392;4608.838690645811;4618.122556714214;4828.927566086511;4842.128416884924;4842.443119041285;10098.14165795901;10105.797884042278;10106.696799096038;10113.190944724405;10119.24105692076;5428.131498960148;5430.15832545956;5432.185172224119;5434.728263586805;5436.782528698887;4915.09070821892;4963.379577552806;4974.950250497521;4983.327250821165;5017.046761115376;3987.541432735273;3998.7400818946676;4040.252553277653;4083.693722970789;4136.875893723193;1182.5860948667898;1222.321611505623;1277.7444511097817;1288.7551365240574;1306.3859985806655;737.6385571070244;783.5416281475286;799.4523751230425;911.1799380557419;927.4945154890601;5618.185363122325;5984.311804142492;6123.693815309306;6124.908126378108;6185.876282474415;929.9256059515222;931.6320053578934;937.0395344771664;941.7833898203452;949.5632009275672;3865.8646947356706;4002.239574480839;4064.309067458539;4093.488313960755;4113.115680128787;10340.948295258475;10714.142868570652;10749;10906.182036347305;10941.015308622262;9482.668106616235;9923.851142232872;10584.412338149185;10717.9464102679;10845.83731244032;7351.264873512713;7494.962525187339;7773.144954055049;7781.45529812919;7781.610919454118;5251.317328747238;5438.673679579079;6057.545684073801;6107.786227482017;6294.024426213944;10258.58965641366;10331.741706457398;10344.224183186141;10345.793084138426;10368.030074285862;9920.652777152765;10318.638847640297;10378.688639340831;10425.478726063819;10578.788424231627;7653.6555854985545;7722.806929826799;7811.062672479339;7821.843563128819;7829.569373684502;8418.536980466133;8493.447925884797;8499.702510412106;8504.192101750325;8514.574271286534;6143.56995010345;7506.211847755907;7548.509346892434;7557.584332861746;7642.7325043623205;7443.292887175709;7473.850522989618;7509.7371591994015;7545.7358992435;7572.242775722604;7004.894926576004;7014.438844892295;7079.68141433647;7092.326229008236;7104.005439238407;4588.105319462673;4931.679440836334;4967.776450059684;5024.547790698854;5097.362829646294;5030.37120359955;5060.468650791614;5088.898222035613;5104.41299250591;5200.297959775382 07:22:23 INFO - PROCESS | 1653 | |4;prototype.html;251.69670546990872;281.9661640603127;287.5973636908328;312.12604706820895;317.27502656430033;4483.7357134647;5427.348718153827;5463.535599474049;5483.972580137099;5513.8345849624575;4332;4467.106578684268;4697.678136114774;5398.055340315449;5423.864403389917;3617.305107694771;3878.1855810279803;4065.8170382332864;4177.770222637754;4232.470941132358;3121.406932682783;3591.7665351752185;3858.151206734514;4086.896537934756;4139.3997870308785;4085.4893138357706;4103.8563650272245;4113.416334711143;4114.6091121343425;4124.030852749616;3323;3539.6460353964526;3781.2815565042556;3945.6448919597374;4008.118213992917;3254.1213872254357;3267.9183020424443;3500.842462089212;3515.874920025593;3529.8235088245483;3895.3572660511263;4732.266498692708;5122.694787345672;5217.530422262014;5224.216367544861;4296.699231053827;4606.170889239936;4618.468876079261;4650.906981860344;4694.976525117352;5205.817796377109;5595.328560572717;5651.943480565224;5663.470862867044;5745.683987380692;3431.7083047940955;3855.575886652466;3858.84564617415;3864.2271545690974;3897.3569361055393;4604.769761511944;5706.77172913083;5761.971190144064;5863.648181109104;5913.526917846562;3824.101336185994;3867.207222519377;3882.514685664292;3887.9805600971813;3905.6484916357094;3926.6858651307825;3932.8820135395986;3950.2889479893897;3964;3975.7216994810087;4025.054112283611;4470.485894122153;4594.356790049395;4602.424696912886;4635.188841952645;5633.605647604629;5633.661980281115;5659.292588426447;5668.404817494186;5675.3757086720425;1151.583552230761;1269.0418733856063;1290.9354532273348;1310.4103153580736;1356.525216174331;723.0419693905596;785.0696924144906;813.7151996801191;824.2581676491205;832.0099082092412;5110.977804439052;5113.054084994288;5295.232191332312;5297.470252974749;5337.959097975935;4855.5387238212315;5324.201369794562;5801.389374781365;5820.679862607597;5837.357890632027;4234.9364759528635;4932.988737308843;5251.658642188246;5284.207368894619;5305.761240744176;3008.916789955614;3016.2610160510817;3032.863521141554;3034.908952731422;3037.9367221472307;5171.267625345551;5637.661740295595;5919.289685237799;5964.463198312173;5970.432808883149;5198.402183748842;5509.696966666792;5938.524918006549;6004.5496587755215;6020.735645514404;4687.343771871941;5254.395744489356;5673.489385955284;6027.638341699512;6105.595712986038;5683.403242659627;5755.194272801895;5920.158324004416;5928.288605367384;5938.910916336172;5278.709670968134;5749.281339832521;5750.281214848144;5756.165356023437;5821.970890145522;5384.811531596375;5551.833444996657;5676.829695109153;5798.739056742541;6013.458788709037;3282.4255755243216;3634.855020668481;3846.4614953907035;3889.902752431212;3892.2410130025505;4691.765411729331;5697.4302569742695;5839.211706419579;5899.056151015817;5899.410058994238;5116.309298244838;5617.197296311595;5700.344460386943;5723.02708539541;5766.59633825645;4329.00432900441;5453.645513041798;5669.688167150846;5677.716114194356;5720.885582288414;4578.88552786183;5590.8043218487155;5623.831285061455;5663.688497132697;5665.461781130786;4690.108879312919;5115.820946266863;5531.419200984;5692.886142277049;5742.856428589319;4189.748615082983;5486.286782718381;5632.802851900164;5633.155026746021;5636.5208957237455 07:22:23 INFO - PROCESS | 1653 | |5;yui.html;675.9222689390721;682.9351211634893;692.2696555134332;704.3132945378254;744.8212429017034;14867.107973521583;15487.148206848618;15977.600559986005;15996.92001539992;16237.431689890856;14611.707765844676;14613;14794.852051479482;14810.111393316409;14862.925685371585;665.7170702451463;693.4694958356861;821.6097353756977;831.2892476932242;851.7700220940343;840.1136800675285;846.3356265331715;848.8769128476367;848.9108643592426;849.1932663969224;897.6005677473527;898.0794685447403;898.7932775461647;899.9415038022507;900.3967341880956;690.0339524665484;839.7816567692413;890.585877566928;895.8432274351962;898.4025622960723;662.6256165266657;664.0669858848325;664.1067763857603;664.6577012838392;664.9534532582672;14749.336279867324;14784.11295322284;14784.926075369554;14787.40850365984;14807;13815.447382104703;13905.374258158407;13910;13976.161430314214;14169.29153542329;12724.363781810873;12775.936120319431;12789.680257993532;13023.283719395524;13263.933680331536;4561.270196768501;4617.492075871652;4759.976200118977;4808.0383923215495;4811.374521312242;102720.43198920012;111675;113840;114223.71552568903;114631;9843.052124660144;11050.115990720884;11085.778284434266;11595.357078861873;11684.831516848417;21817.890910545346;21851.235206768004;21867;21889;21889.67165492519;7778.8833167502535;7877.7636670899965;7932.524048557105;7941.166177551389;8002.399820013522;3610.4403817408343;4267.125239325931;4311.33174357975;4323;4447.577480139382;2966.569847372119;3566.144125409883;3595.1371670799344;3599.586047604507;3932.6628946158444;558.5065415824075;657.7174509706092;740.5630677900065;751.8270797716556;752.785456144996;549.1707521642348;551.7792882846813;606.5905513778182;641.5893827950116;682.2938258902012;9381.249500039981;9429.575669094907;9547.2839537035;9580.56887440053;9588.328816982745;625.343389441085;639.8510315864538;649.574528683713;649.7173729427619;650.0541711809307;4581.335706322631;4651.37206477128;4653.232216684277;4653.302004699254;4663;158105.62841557385;163152;172020;173539;176834;59868;103338.4499232497;111109;136080.31959840335;147168;102732;103154.48422757989;104890;110222.28444009002;111315.33027004602;9655.330515907834;9851.029794041076;9856.21150307988;9894.208463322773;9898.851517227104;50745.492545075555;52013;55866.72066639641;57685.711571441876;62188;64071;84510;85876;94931.52534237284;99611;13213.933930330288;13474.595762127152;13484.258365789976;13843.515476958259;13856.722865542431;14840.925795370955;14859;14902.25488725581;14926.477573284883;15266.923665381602;14023.088499247517;14033.578992630237;14059.507917222849;14183.14901105937;14550.199739014455;12552.560660376843;13030.804537931748;13261.867381326449;13282;13348.666283342995;13570.253636050113;13787.51743688966;13945.930270348583;14120.435182592992;14244.857551424768;11362.431878405881;11464.025557827677;11611.187216894736;11753.412329383667;11956.163068585116;10926.836097458703;11049.226554141133;11669.299842009506;11672.941635291769;11743.354115523729 07:22:23 INFO - PROCESS | 1653 | __end_tp_report 07:22:23 INFO - PROCESS | 1653 | __start_cc_report 07:22:23 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1192 07:22:23 INFO - PROCESS | 1653 | __end_cc_report 07:22:23 INFO - PROCESS | 1653 | __startTimestamp1467296543117__endTimestamp 07:22:23 INFO - PROCESS | 1653 | 07:22:23 INFO - PROCESS | 1653 | ------- Summary: start ------- 07:22:23 INFO - PROCESS | 1653 | Number of tests: 6 07:22:23 INFO - PROCESS | 1653 | 07:22:23 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5707.32 Median:5860.94 stddev:626.72 (10.7%) stddev-sans-first:600.28 07:22:23 INFO - PROCESS | 1653 | Values: 3331.3 3331.6 3370.2 3380.8 3418.6 5546.8 5576.7 5607.6 5658.9 5676.7 5605.8 5619.4 5737.2 5749.7 5793.0 5077.4 5125.6 5159.6 5184.6 5267.4 5152.5 5286.4 5288.7 5294.4 5335.2 5069.2 5150.4 5164.2 5188.0 5221.3 5117.4 5185.3 5189.2 5192.9 5213.8 5135.9 5147.3 5188.7 5220.5 5257.0 5668.3 5674.3 5689.8 5758.7 5770.1 5899.1 6136.3 6199.5 6203.1 6203.3 6173.0 6243.2 6244.5 6256.3 6258.5 5518.5 5750.8 5758.1 5764.0 5778.8 6160.3 6216.0 6284.6 6285.9 6331.2 5918.4 5972.8 6080.0 6138.5 6152.9 5944.6 6100.9 6231.4 6250.0 6286.1 6029.2 6062.6 6115.3 6135.4 6217.3 6145.8 6236.9 6240.3 6242.9 6266.3 4842.5 4893.5 4931.7 4975.4 5010.3 4377.1 4403.8 4412.5 4414.2 4433.2 5628.5 5690.7 5696.9 5716.2 5724.7 6109.8 6119.6 6235.5 6295.7 6297.7 5595.0 5650.3 5663.0 5674.1 5708.7 5411.5 5432.0 5442.0 5447.8 5452.6 6230.0 6297.0 6334.7 6411.2 6458.3 6070.2 6087.9 6101.5 6173.9 6259.0 6302.7 6314.1 6361.2 6377.6 6388.7 5860.9 5924.5 6114.1 6129.0 6140.0 6158.0 6191.5 6275.4 6295.9 6316.0 6074.5 6083.7 6103.2 6103.3 6112.0 5370.5 5737.2 5897.7 5973.9 6011.6 5839.1 5884.1 5898.8 5902.4 5943.9 6040.2 6067.8 6076.6 6084.5 6088.9 5682.8 5762.8 5778.7 5819.9 5898.7 07:22:23 INFO - PROCESS | 1653 | 07:22:23 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:68046.82 Median:40522.09 stddev:146576.55 (361.7%) stddev-sans-first:146899.98 07:22:23 INFO - PROCESS | 1653 | Values: 490.9 493.5 497.1 517.0 526.0 31459.4 41788.0 42700.0 42934.6 45468.5 43759.0 44066.8 44168.1 44277.8 44341.9 8790.8 9694.4 9887.6 9904.3 9904.4 8738.4 8974.3 8981.5 8987.6 12025.5 10424.1 11931.4 12139.3 12238.4 12274.8 12105.0 12167.7 12175.5 12177.6 12182.7 8705.2 8915.9 8916.4 9034.9 11942.3 41149.4 41645.8 41653.8 43922.0 44181.0 3395.2 3726.8 4134.8 4179.6 4323.0 33121.8 33222.7 33246.8 33274.5 33425.5 3207.8 3429.6 3474.1 3478.1 3481.9 373774.0 374508.0 374677.0 376865.0 382813.0 52872.0 54162.7 54323.2 63885.0 64527.4 67668.0 67827.7 67828.7 67889.7 67978.0 15200.8 16891.2 17139.4 17177.6 17180.8 11758.5 12047.0 12058.0 12065.8 12095.3 7724.1 7794.2 14715.8 18351.4 19234.3 6771.9 7821.0 7978.9 8059.1 8092.6 883.6 964.7 1001.2 1064.1 1081.0 25486.5 30636.4 31298.0 32068.4 32348.0 1027.1 1029.2 1052.6 1069.2 1111.1 14039.2 16116.0 16460.6 16502.3 16645.8 697672.0 701127.0 708031.0 708755.0 710078.0 62695.7 63386.7 63404.7 63472.0 63515.0 1246.0 1278.7 1345.9 1361.1 1388.1 30666.2 32813.7 32861.7 33294.1 33402.5 52729.7 53645.2 53713.2 53745.7 53879.0 493609.0 506806.0 517248.0 517324.0 522267.0 22986.9 24460.7 27305.3 27906.2 27990.2 48867.3 50240.0 51173.0 51544.0 51648.7 38831.4 41456.8 42226.8 42467.0 42563.0 39646.6 41047.8 41260.8 41382.0 42368.2 39734.6 39789.8 40430.0 40614.2 40695.2 33157.8 35087.0 35807.0 36024.0 36059.0 22622.7 25679.2 25806.9 26088.3 26673.2 07:22:23 INFO - PROCESS | 1653 | 07:22:23 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:33665.74 Median:26725.80 stddev:98009.82 (366.7%) stddev-sans-first:98252.86 07:22:23 INFO - PROCESS | 1653 | Values: 363.2 369.4 388.1 393.3 402.0 15695.5 16444.1 20684.4 21206.8 21239.2 21342.9 21581.7 21619.4 21665.6 21674.9 5910.0 5914.3 6082.7 6141.8 6187.2 6058.8 6066.5 6086.3 6100.4 6121.5 5733.6 6017.5 6020.5 6031.6 6067.7 5667.4 5927.1 5937.5 5963.1 5972.0 5169.5 5184.8 5214.7 5216.5 5232.5 26510.0 26514.3 26528.7 26556.0 26895.6 2215.4 2267.7 2366.8 2386.0 2513.7 15151.5 15729.6 15922.8 16306.5 16475.8 1859.9 1877.3 1897.1 1907.2 1929.8 88576.1 106907.0 107379.0 107791.5 108808.5 10829.6 10955.2 11281.5 11316.7 11448.7 13946.9 14058.4 14059.6 14097.6 14108.3 8569.4 8773.6 8828.3 8858.0 8885.3 7152.7 8754.7 8790.7 8928.9 8954.4 6751.5 6900.6 6906.6 6918.5 6924.3 1856.6 1897.9 1898.6 1898.9 1901.4 897.0 913.1 921.4 982.0 1036.6 21853.9 22636.3 23036.4 23068.0 23079.3 878.3 914.3 965.6 994.2 1049.3 11889.0 12041.2 12215.8 12238.4 12254.5 581344.0 588210.0 596102.0 600739.0 602672.0 66062.3 86492.0 96445.5 106765.6 116616.0 947.2 975.7 979.6 1011.5 1012.9 17258.9 19658.8 19912.8 20377.7 20583.4 51852.0 52141.0 52363.5 52970.7 53702.0 68379.7 68875.7 69770.7 69833.3 71227.2 6959.3 7310.2 7982.0 8035.2 8252.9 11978.6 12846.2 12970.7 13063.0 13214.4 9564.3 11213.0 11309.1 11310.9 11584.8 9712.0 11075.4 11256.4 11265.4 11413.4 11296.9 11386.1 11443.9 11638.2 11647.0 6434.8 7339.7 7422.1 7460.7 7461.3 8298.5 11093.2 11462.8 11484.3 11675.6 07:22:23 INFO - PROCESS | 1653 | 07:22:23 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5761.92 Median:4965.58 stddev:3229.19 (65.0%) stddev-sans-first:3222.55 07:22:23 INFO - PROCESS | 1653 | Values: 1524.1 1554.8 1741.4 1881.5 1892.0 8491.9 8784.6 8978.4 8980.9 9008.6 7828.0 8087.4 8576.8 8756.0 8821.4 2370.8 2398.7 2399.2 2401.5 2404.1 2360.5 2369.8 2408.0 2410.4 2413.4 2109.7 2197.8 2310.1 2389.0 2389.0 1980.9 2174.3 2236.9 2366.8 2406.7 2357.9 2380.2 2382.6 2383.4 2397.5 8922.9 9038.9 9057.7 9068.1 9092.4 5279.6 5436.3 5450.4 5450.9 5457.7 3579.1 3590.5 3642.2 3644.9 3653.1 1067.8 1076.3 1085.4 1097.2 1099.2 11949.4 11951.4 11954.5 11961.6 11970.6 4608.8 4618.1 4828.9 4842.1 4842.4 10098.1 10105.8 10106.7 10113.2 10119.2 5428.1 5430.2 5432.2 5434.7 5436.8 4915.1 4963.4 4975.0 4983.3 5017.0 3987.5 3998.7 4040.3 4083.7 4136.9 1182.6 1222.3 1277.7 1288.8 1306.4 737.6 783.5 799.5 911.2 927.5 5618.2 5984.3 6123.7 6124.9 6185.9 929.9 931.6 937.0 941.8 949.6 3865.9 4002.2 4064.3 4093.5 4113.1 10340.9 10714.1 10749.0 10906.2 10941.0 9482.7 9923.9 10584.4 10717.9 10845.8 7351.3 7495.0 7773.1 7781.5 7781.6 5251.3 5438.7 6057.5 6107.8 6294.0 10258.6 10331.7 10344.2 10345.8 10368.0 9920.7 10318.6 10378.7 10425.5 10578.8 7653.7 7722.8 7811.1 7821.8 7829.6 8418.5 8493.4 8499.7 8504.2 8514.6 6143.6 7506.2 7548.5 7557.6 7642.7 7443.3 7473.9 7509.7 7545.7 7572.2 7004.9 7014.4 7079.7 7092.3 7104.0 4588.1 4931.7 4967.8 5024.5 5097.4 5030.4 5060.5 5088.9 5104.4 5200.3 07:22:23 INFO - PROCESS | 1653 | 07:22:23 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4531.76 Median:5146.98 stddev:1422.73 (27.6%) stddev-sans-first:1389.98 07:22:23 INFO - PROCESS | 1653 | Values: 251.7 282.0 287.6 312.1 317.3 4483.7 5427.3 5463.5 5484.0 5513.8 4332.0 4467.1 4697.7 5398.1 5423.9 3617.3 3878.2 4065.8 4177.8 4232.5 3121.4 3591.8 3858.2 4086.9 4139.4 4085.5 4103.9 4113.4 4114.6 4124.0 3323.0 3539.6 3781.3 3945.6 4008.1 3254.1 3267.9 3500.8 3515.9 3529.8 3895.4 4732.3 5122.7 5217.5 5224.2 4296.7 4606.2 4618.5 4650.9 4695.0 5205.8 5595.3 5651.9 5663.5 5745.7 3431.7 3855.6 3858.8 3864.2 3897.4 4604.8 5706.8 5762.0 5863.6 5913.5 3824.1 3867.2 3882.5 3888.0 3905.6 3926.7 3932.9 3950.3 3964.0 3975.7 4025.1 4470.5 4594.4 4602.4 4635.2 5633.6 5633.7 5659.3 5668.4 5675.4 1151.6 1269.0 1290.9 1310.4 1356.5 723.0 785.1 813.7 824.3 832.0 5111.0 5113.1 5295.2 5297.5 5338.0 4855.5 5324.2 5801.4 5820.7 5837.4 4234.9 4933.0 5251.7 5284.2 5305.8 3008.9 3016.3 3032.9 3034.9 3037.9 5171.3 5637.7 5919.3 5964.5 5970.4 5198.4 5509.7 5938.5 6004.5 6020.7 4687.3 5254.4 5673.5 6027.6 6105.6 5683.4 5755.2 5920.2 5928.3 5938.9 5278.7 5749.3 5750.3 5756.2 5822.0 5384.8 5551.8 5676.8 5798.7 6013.5 3282.4 3634.9 3846.5 3889.9 3892.2 4691.8 5697.4 5839.2 5899.1 5899.4 5116.3 5617.2 5700.3 5723.0 5766.6 4329.0 5453.6 5669.7 5677.7 5720.9 4578.9 5590.8 5623.8 5663.7 5665.5 4690.1 5115.8 5531.4 5692.9 5742.9 4189.7 5486.3 5632.8 5633.2 5636.5 07:22:23 INFO - PROCESS | 1653 | 07:22:23 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:24677.88 Median:3597.36 stddev:40103.70 (1114.8%) stddev-sans-first:40175.71 07:22:23 INFO - PROCESS | 1653 | Values: 675.9 682.9 692.3 704.3 744.8 14867.1 15487.1 15977.6 15996.9 16237.4 14611.7 14613.0 14794.9 14810.1 14862.9 665.7 693.5 821.6 831.3 851.8 840.1 846.3 848.9 848.9 849.2 897.6 898.1 898.8 899.9 900.4 690.0 839.8 890.6 895.8 898.4 662.6 664.1 664.1 664.7 665.0 14749.3 14784.1 14784.9 14787.4 14807.0 13815.4 13905.4 13910.0 13976.2 14169.3 12724.4 12775.9 12789.7 13023.3 13263.9 4561.3 4617.5 4760.0 4808.0 4811.4 102720.4 111675.0 113840.0 114223.7 114631.0 9843.1 11050.1 11085.8 11595.4 11684.8 21817.9 21851.2 21867.0 21889.0 21889.7 7778.9 7877.8 7932.5 7941.2 8002.4 3610.4 4267.1 4311.3 4323.0 4447.6 2966.6 3566.1 3595.1 3599.6 3932.7 558.5 657.7 740.6 751.8 752.8 549.2 551.8 606.6 641.6 682.3 9381.2 9429.6 9547.3 9580.6 9588.3 625.3 639.9 649.6 649.7 650.1 4581.3 4651.4 4653.2 4653.3 4663.0 158105.6 163152.0 172020.0 173539.0 176834.0 59868.0 103338.4 111109.0 136080.3 147168.0 102732.0 103154.5 104890.0 110222.3 111315.3 9655.3 9851.0 9856.2 9894.2 9898.9 50745.5 52013.0 55866.7 57685.7 62188.0 64071.0 84510.0 85876.0 94931.5 99611.0 13213.9 13474.6 13484.3 13843.5 13856.7 14840.9 14859.0 14902.3 14926.5 15266.9 14023.1 14033.6 14059.5 14183.1 14550.2 12552.6 13030.8 13261.9 13282.0 13348.7 13570.3 13787.5 13945.9 14120.4 14244.9 11362.4 11464.0 11611.2 11753.4 11956.2 10926.8 11049.2 11669.3 11672.9 11743.4 07:22:23 INFO - PROCESS | 1653 | -------- Summary: end -------- 07:22:23 INFO - PROCESS | 1653 | 07:22:23 INFO - TEST-INFO | 1653: exit 0 07:22:23 INFO - TEST-OK | dromaeo_css | took 1117212ms 07:22:23 INFO - TEST-START | kraken 07:22:23 INFO - operating with platform_type : mac_ 07:22:23 INFO - Initialising browser for kraken test... 07:22:23 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmp6O2Mhu/profile http://localhost:49271/getInfo.html) 07:22:25 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 07:22:25 INFO - PROCESS | 1662 | colorDepth:24 07:22:25 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 07:22:25 INFO - PROCESS | 1662 | __metrics 07:22:26 INFO - PROCESS | 1662 | 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" 07:22:26 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:22:26 INFO - TEST-INFO | 1662: exit 0 07:22:26 INFO - Browser initialized. 07:22:26 INFO - Running cycle 1/1 for kraken test... 07:22:26 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmp6O2Mhu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:22:38 INFO - PROCESS | 1663 | RSS: Main: 244797440 07:22:38 INFO - PROCESS | 1663 | 07:22:41 INFO - PROCESS | 1663 | 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) 07:22:42 INFO - PROCESS | 1663 | RSS: Main: 294121472 07:22:42 INFO - PROCESS | 1663 | 07:22:44 INFO - PROCESS | 1663 | 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) 07:22:45 INFO - PROCESS | 1663 | RSS: Main: 305225728 07:22:45 INFO - PROCESS | 1663 | 07:22:49 INFO - PROCESS | 1663 | 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) 07:22:49 INFO - PROCESS | 1663 | RSS: Main: 392335360 07:22:49 INFO - PROCESS | 1663 | 07:22:51 INFO - PROCESS | 1663 | 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) 07:22:52 INFO - PROCESS | 1663 | RSS: Main: 290365440 07:22:52 INFO - PROCESS | 1663 | 07:22:54 INFO - PROCESS | 1663 | 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) 07:22:55 INFO - PROCESS | 1663 | RSS: Main: 294281216 07:22:55 INFO - PROCESS | 1663 | 07:22:57 INFO - PROCESS | 1663 | 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) 07:22:58 INFO - PROCESS | 1663 | RSS: Main: 331735040 07:22:58 INFO - PROCESS | 1663 | 07:23:01 INFO - PROCESS | 1663 | 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) 07:23:01 INFO - PROCESS | 1663 | RSS: Main: 330706944 07:23:01 INFO - PROCESS | 1663 | 07:23:04 INFO - PROCESS | 1663 | 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) 07:23:05 INFO - PROCESS | 1663 | RSS: Main: 332292096 07:23:05 INFO - PROCESS | 1663 | 07:23:06 INFO - PROCESS | 1663 | 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) 07:23:07 INFO - PROCESS | 1663 | RSS: Main: 313266176 07:23:07 INFO - PROCESS | 1663 | 07:23:09 INFO - PROCESS | 1663 | 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) 07:23:10 INFO - PROCESS | 1663 | RSS: Main: 320348160 07:23:10 INFO - PROCESS | 1663 | 07:23:12 INFO - PROCESS | 1663 | 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) 07:23:13 INFO - PROCESS | 1663 | RSS: Main: 317825024 07:23:13 INFO - PROCESS | 1663 | 07:23:15 INFO - PROCESS | 1663 | 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) 07:23:16 INFO - PROCESS | 1663 | RSS: Main: 327995392 07:23:16 INFO - PROCESS | 1663 | 07:23:19 INFO - PROCESS | 1663 | 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) 07:23:19 INFO - PROCESS | 1663 | RSS: Main: 329351168 07:23:19 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | 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) 07:23:21 INFO - PROCESS | 1663 | RSS: Main: 372846592 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | __start_tp_report 07:23:21 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 07:23:21 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 07:23:21 INFO - PROCESS | 1663 | |i|pagename|runs| 07:23:21 INFO - PROCESS | 1663 | |0;ai-astar;101;112;114;113;113;112;113;112;112;110 07:23:21 INFO - PROCESS | 1663 | |1;audio-beat-detection;154;137;135;157;106;126;129;107;160;107 07:23:21 INFO - PROCESS | 1663 | |2;audio-dft;184;184;183;187;182;183;180;184;181;184 07:23:21 INFO - PROCESS | 1663 | |3;audio-fft;80;75;58;108;58;57;60;62;97;56 07:23:21 INFO - PROCESS | 1663 | |4;audio-oscillator;86;95;93;71;72;70;96;73;69;113 07:23:21 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;87;84;87;84;87;83;88;84;88;84 07:23:21 INFO - PROCESS | 1663 | |6;imaging-darkroom;92;92;94;94;93;93;93;94;93;92 07:23:21 INFO - PROCESS | 1663 | |7;imaging-desaturate;128;142;84;83;86;84;83;92;128;86 07:23:21 INFO - PROCESS | 1663 | |8;json-parse-financial;55;53;53;55;56;53;54;53;54;53 07:23:21 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;65;73;79;76;73;70;72;72;70;75 07:23:21 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;86;83;82;84;82;84;84;82;86;83 07:23:21 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;137;136;136;140;138;143;141;138;194;151 07:23:21 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;161;163;161;161;163;161;163;163;162;162 07:23:21 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;67;66;66;67;66;67;67;66;67;66 07:23:21 INFO - PROCESS | 1663 | __end_tp_report 07:23:21 INFO - PROCESS | 1663 | __start_cc_report 07:23:21 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2070 07:23:21 INFO - PROCESS | 1663 | __end_cc_report 07:23:21 INFO - PROCESS | 1663 | __startTimestamp1467296601838__endTimestamp 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | ------- Summary: start ------- 07:23:21 INFO - PROCESS | 1663 | Number of tests: 14 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.50 stddev:3.74 (3.3%) stddev-sans-first:1.12 07:23:21 INFO - PROCESS | 1663 | Values: 101.0 112.0 114.0 113.0 113.0 112.0 113.0 112.0 112.0 110.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:136.00 stddev:20.81 (15.3%) stddev-sans-first:20.46 07:23:21 INFO - PROCESS | 1663 | Values: 154.0 137.0 135.0 157.0 106.0 126.0 129.0 107.0 160.0 107.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.93 (1.1%) stddev-sans-first:2.03 07:23:21 INFO - PROCESS | 1663 | Values: 184.0 184.0 183.0 187.0 182.0 183.0 180.0 184.0 181.0 184.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:71.10 Median:61.00 stddev:18.57 (30.4%) stddev-sans-first:19.41 07:23:21 INFO - PROCESS | 1663 | Values: 80.0 75.0 58.0 108.0 58.0 57.0 60.0 62.0 97.0 56.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:83.80 Median:79.50 stddev:15.08 (19.0%) stddev-sans-first:15.97 07:23:21 INFO - PROCESS | 1663 | Values: 86.0 95.0 93.0 71.0 72.0 70.0 96.0 73.0 69.0 113.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:87.00 stddev:1.96 (2.2%) stddev-sans-first:2.01 07:23:21 INFO - PROCESS | 1663 | Values: 87.0 84.0 87.0 84.0 87.0 83.0 88.0 84.0 88.0 84.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 07:23:21 INFO - PROCESS | 1663 | Values: 92.0 92.0 94.0 94.0 93.0 93.0 93.0 94.0 93.0 92.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:84.00 stddev:23.28 (27.7%) stddev-sans-first:22.31 07:23:21 INFO - PROCESS | 1663 | Values: 128.0 142.0 84.0 83.0 86.0 84.0 83.0 92.0 128.0 86.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.10 (2.0%) stddev-sans-first:1.09 07:23:21 INFO - PROCESS | 1663 | Values: 55.0 53.0 53.0 55.0 56.0 53.0 54.0 53.0 54.0 53.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:73.00 stddev:3.81 (5.2%) stddev-sans-first:2.92 07:23:21 INFO - PROCESS | 1663 | Values: 65.0 73.0 79.0 76.0 73.0 70.0 72.0 72.0 70.0 75.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:1.51 (1.8%) stddev-sans-first:1.32 07:23:21 INFO - PROCESS | 1663 | Values: 86.0 83.0 82.0 84.0 82.0 84.0 84.0 82.0 86.0 83.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:140.50 stddev:17.65 (12.6%) stddev-sans-first:18.46 07:23:21 INFO - PROCESS | 1663 | Values: 137.0 136.0 136.0 140.0 138.0 143.0 141.0 138.0 194.0 151.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.00 Median:162.50 stddev:0.94 (0.6%) stddev-sans-first:0.93 07:23:21 INFO - PROCESS | 1663 | Values: 161.0 163.0 161.0 161.0 163.0 161.0 163.0 163.0 162.0 162.0 07:23:21 INFO - PROCESS | 1663 | 07:23:21 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 07:23:21 INFO - PROCESS | 1663 | Values: 67.0 66.0 66.0 67.0 66.0 67.0 67.0 66.0 67.0 66.0 07:23:21 INFO - PROCESS | 1663 | -------- Summary: end -------- 07:23:21 INFO - PROCESS | 1663 | 07:23:22 INFO - TEST-INFO | 1663: exit 0 07:23:22 INFO - TEST-OK | kraken | took 58893ms 07:23:22 INFO - SUITE-END | took 1176s 07:23:22 INFO - Completed test suite (00:19:36) 07:23:22 INFO - javascript benchmark 07:23:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3331.3170799986, 3331.600207975042, 3370.2080011197363, 3380.8309584520766, 3418.641042690517, 5546.750396232169, 5576.721163941802, 5607.607467477278, 5658.943410565893, 5676.659400435971, 5605.803796867111, 5619.438056194368, 5737.196792449061, 5749.712514374285, 5793.0, 5077.41609714882, 5125.564327032188, 5159.638825282232, 5184.585233181337, 5267.3942496613, 5152.484751524855, 5286.392064912546, 5288.6562373445795, 5294.391145018334, 5335.1730481775385, 5069.163588007974, 5150.381954165487, 5164.225366195101, 5188.04021256067, 5221.321228240305, 5117.437081921023, 5185.325907632021, 5189.195674670432, 5192.92210616841, 5213.791448342062, 5135.897282054375, 5147.305113809625, 5188.688678679254, 5220.504052115043, 5257.0, 5668.31980162379, 5674.347450043257, 5689.800856969986, 5758.6544807311275, 5770.134479828017, 5899.085641725539, 6136.274005700925, 6199.473044791153, 6203.131561581382, 6203.348648391944, 6172.969135154341, 6243.219597550306, 6244.531660125509, 6256.343083976208, 6258.4680302174365, 5518.503334699896, 5750.769969201185, 5758.107493338539, 5764.0, 5778.8266352009505, 6160.260768707694, 6215.96892015537, 6284.5600807943, 6285.9057114143325, 6331.240251169889, 5918.378570250148, 5972.790952316735, 6079.969600151971, 6138.508919286446, 6152.938470615326, 5944.613600115978, 6100.938990610126, 6231.376862313732, 6249.96875015622, 6286.088517164985, 6029.216201893813, 6062.636241825505, 6115.327313995457, 6135.385782596134, 6217.316095229521, 6145.845290797189, 6236.875262494725, 6240.251169859555, 6242.906356404657, 6266.310705822356, 4842.539958703917, 4893.510648935078, 4931.679440836334, 4975.378077740283, 5010.2735103409805, 4377.0808130293, 4403.8238470461465, 4412.536683648235, 4414.161309351213, 4433.179861725591, 5628.465295796893, 5690.715464226772, 5696.857578560569, 5716.1997320375185, 5724.685142317129, 6109.75560977556, 6119.563485741553, 6235.469985051322, 6295.716692748837, 6297.685115744194, 5595.0, 5650.26546548946, 5663.0, 5674.148877668383, 5708.743106560215, 5411.458854114557, 5432.0, 5442.0, 5447.782088716407, 5452.59105567084, 6230.0, 6297.023961286008, 6334.683265836782, 6411.166548348685, 6458.289588145394, 6070.2108725865355, 6087.9391206087375, 6101.450869421773, 6173.876522469435, 6258.998560230341, 6302.653354065387, 6314.1160237565855, 6361.236651601837, 6377.649229292433, 6388.744450221939, 5860.941390586039, 5924.496417804535, 6114.113453549299, 6128.9693551531955, 6140.048292514667, 6157.953147964767, 6191.504679625529, 6275.435210831047, 6295.874082518416, 6316.021016742412, 6074.544409169242, 6083.69581520931, 6103.206583144163, 6103.328633850184, 6111.96944015277, 5370.516653501204, 5737.225474560881, 5897.67562784051, 5973.850653733692, 6011.579189456871, 5839.065749480063, 5884.08796636522, 5898.8230353089475, 5902.409759024235, 5943.881122377442, 6040.2147720797775, 6067.787627433196, 6076.605020673642, 6084.543659225665, 6088.878222435438, 5682.829515114553, 5762.798302059407, 5778.739956702043, 5819.883602327845, 5898.675572843535], "value": 5667.241761031865, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.8870959679274, 493.5336107378529, 497.1076916934103, 516.9922451163236, 526.0321009342808, 31459.370812583733, 41788.0, 42700.0, 42934.57065429345, 45468.54531454684, 43759.0, 44066.779666101706, 44168.116637667226, 44277.7786111069, 44341.891452713695, 8790.824183516326, 9694.418334899929, 9887.555060022316, 9904.257180711475, 9904.405735655848, 8738.432001919886, 8974.28205743539, 8981.506017169053, 8987.640494380217, 12025.518979240864, 10424.113950314195, 11931.403429828517, 12139.271643701406, 12238.38808059598, 12274.815877761795, 12104.971077458427, 12167.695807604792, 12175.45210465531, 12177.573784917575, 12182.695432614168, 8705.173008564176, 8915.910840891573, 8916.420432671855, 9034.86447703285, 11942.343171125585, 41149.38275925864, 41645.79177104125, 41653.791731041456, 43922.0, 44181.0, 3395.185155562647, 3726.757760745543, 4134.834606615732, 4179.582041795827, 4323.027318853233, 33121.83439082813, 33222.6677733222, 33246.83376583126, 33274.500882486784, 33425.49861752076, 3207.839608019613, 3429.6227414984332, 3474.096734848932, 3478.147853775841, 3481.895543133685, 373774.0, 374508.0, 374677.0, 376865.0, 382812.9999999972, 52872.0, 54162.72918635382, 54323.18515222275, 63885.04172437417, 64527.35472645213, 67668.0, 67827.66086169537, 67828.6608566954, 67889.66055169693, 67978.0, 15200.847991520164, 16891.15544222784, 17139.400120995706, 17177.570560735832, 17180.828191718174, 11758.470868810924, 12047.0, 12058.0, 12065.758684826429, 12095.334756588472, 7724.0731112266885, 7794.181610930884, 14715.789394627895, 18351.449456516326, 19234.326798561982, 6771.864562708718, 7821.045886052373, 7978.880316795253, 8059.073206581201, 8092.635831387602, 883.5626364949392, 964.6671898195111, 1001.234055947201, 1064.1114669251108, 1080.9945950270198, 25486.490270194492, 30636.387272254877, 31298.0, 32068.358632827214, 32348.029559113264, 1027.1372047480152, 1029.1715169288645, 1052.6157952347337, 1069.2034434346463, 1111.0833562311125, 14039.157650541, 16116.0, 16460.588485287724, 16502.33990640361, 16645.83354166467, 697672.0, 701127.0, 708031.0, 708755.0, 710078.0, 62695.6865215671, 63386.73226535536, 63404.68297658482, 63472.04791928125, 63515.0, 1245.9813102803466, 1278.6611547939804, 1345.9125156864773, 1361.1424802374638, 1388.1324172392256, 30666.233344166576, 32813.67186328202, 32861.67138328682, 33294.07262733635, 33402.49896251607, 52729.736351317995, 53645.195322070984, 53713.19430208472, 53745.73127134339, 53879.0, 493609.0, 506806.0, 517248.0, 517324.0, 522267.0, 22986.885065574566, 24460.65466399365, 27305.269473052536, 27906.16281511558, 27990.160295190362, 48867.26699099445, 50240.0, 51173.0, 51544.0, 51648.708782280744, 38831.41752873653, 41456.79271603622, 42226.788866055475, 42467.0, 42563.0, 39646.60353396429, 41047.794761026, 41260.79369603133, 41382.0, 42368.15263694647, 39734.60265397309, 39789.806305810875, 40430.0, 40614.1877162461, 40695.1860962785, 33157.83421082879, 35087.0, 35807.0, 36024.0, 36059.0, 22622.660660089783, 25679.229623111336, 25806.8709656458, 26088.34779130537, 26673.19980400591], "value": 20439.044260852046, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.1719679131581, 369.44398679986625, 388.06282826972864, 393.3333000564043, 401.96181362770545, 15695.52913412598, 16444.095574743384, 20684.379468615967, 21206.833624150666, 21239.15043398266, 21342.932853357346, 21581.67627485589, 21619.3514194574, 21665.56668866622, 21674.91625418727, 5909.970450147754, 5914.319853216871, 6082.695865206744, 6141.846453838645, 6187.164732761087, 6058.787942422016, 6066.514678825683, 6086.269647642267, 6100.389961003886, 6121.540884433663, 5733.627314224583, 6017.458428741413, 6020.488258498033, 6031.60794548353, 6067.666278354689, 5667.4049224831215, 5927.081302398135, 5937.495312898387, 5963.105534169866, 5972.014617588115, 5169.457206993249, 5184.818531351385, 5214.713190774506, 5216.478352164791, 5232.502912223333, 26510.0, 26514.337141571614, 26528.73471265282, 26556.0, 26895.596566051525, 2215.435064058655, 2267.671187677794, 2366.7514910934447, 2386.045581767307, 2513.673222481084, 15151.545453636409, 15729.606759831096, 15922.76115858263, 16306.510804675878, 16475.752863707054, 1859.8512119030581, 1877.2584828992472, 1897.0704354866155, 1907.160849226336, 1929.778075521319, 88576.11423885744, 106907.0, 107379.0, 107791.46104269428, 108808.4559577205, 10829.566817327219, 10955.233133680566, 11281.548738050386, 11316.660500185008, 11448.713782155346, 13946.86053139476, 14058.437662493387, 14059.578212653636, 14097.647558811112, 14108.2945852709, 8569.442986205877, 8773.649054037895, 8828.293736501064, 8858.025617182106, 8885.333599980027, 7152.749653762094, 8754.69358572447, 8790.736277911672, 8928.866067009, 8954.417962832395, 6751.459883209332, 6900.5859648420255, 6906.585604863724, 6918.481113916477, 6924.3421874921805, 1856.6193930244267, 1897.8743806936043, 1898.6012937283326, 1898.943031709051, 1901.3820508334848, 896.9550473698068, 913.0823522359985, 921.3826736086838, 981.9607215711292, 1036.6371769880482, 21853.890730546562, 22636.320910372717, 23036.4240893979, 23068.0, 23079.307620771404, 878.3017501086651, 914.2503147419053, 965.5992763003275, 994.16986816008, 1049.2707568240153, 11889.0, 12041.217320874115, 12215.755684886253, 12238.449269782881, 12254.509819607116, 581344.0, 588210.0, 596102.0, 600739.0, 602672.0, 66062.33937660563, 86492.0, 96445.51777241209, 106765.57181203474, 116616.0, 947.2422062350092, 975.6829030565095, 979.5738853598707, 1011.5195282240877, 1012.8733908261468, 17258.913705431394, 19658.8204707718, 19912.800871991672, 20377.69433458529, 20583.38249852507, 51852.0, 52140.95718085541, 52363.47636523738, 52970.73514632402, 53702.0, 68379.65810171116, 68875.65562172157, 69770.65114674394, 69833.30166698268, 71227.21931951743, 6959.338862808025, 7310.159331676925, 7982.0, 8035.156308587514, 8252.917470825216, 11978.58074967372, 12846.164999275017, 12970.675733106747, 13063.0, 13214.405351759002, 9564.330496865152, 11213.046891014355, 11309.095272378354, 11310.94344528305, 11584.768304633692, 9711.951440242754, 11075.446227688422, 11256.437178140897, 11265.380404077527, 11413.429328533706, 11296.943515282372, 11386.146039046938, 11443.885561144614, 11638.185327027028, 11647.0, 6434.8391290218115, 7339.669714862737, 7422.109346878409, 7460.738874139379, 7461.328480436787, 8298.543580103153, 11093.22347435704, 11462.828057578976, 11484.310941343547, 11675.649730508097], "value": 9704.017757802898, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.0703171065652, 1554.8445155484449, 1741.3556983915953, 1881.5390229393784, 1891.9905400472996, 8491.872621910676, 8784.648614055446, 8978.416402933804, 8980.910190898088, 9008.639654413824, 7827.960860195705, 8087.433879628428, 8576.785580360494, 8756.0, 8821.382503224777, 2370.786629203367, 2398.688170537828, 2399.196269249803, 2401.4836810085826, 2404.1345115758313, 2360.504294098233, 2369.8104151667826, 2408.0, 2410.409449684821, 2413.4087148648605, 2109.6940943563172, 2197.7692342304067, 2310.07596961216, 2388.976110238893, 2388.976110238893, 1980.9306674266475, 2174.325958952722, 2236.8769717665523, 2366.751491093428, 2406.6871306730186, 2357.9410514737096, 2380.238323736405, 2382.6187809950325, 2383.368407372048, 2397.460571371445, 8922.910770892338, 9038.909610903873, 9057.728268151966, 9068.138526839939, 9092.363534552584, 5279.63042587019, 5436.293281873332, 5450.427705090988, 5450.890982180374, 5457.699826509541, 3579.1410061585293, 3590.4793804898404, 3642.1987162824134, 3644.9271014579826, 3653.0684675407865, 1067.8024565455337, 1076.262760009396, 1085.3704851186294, 1097.237419993105, 1099.1811100729929, 11949.402529873558, 11951.402429878472, 11954.46204920772, 11961.581344652895, 11970.58102966392, 4608.838690645811, 4618.122556714214, 4828.927566086511, 4842.128416884924, 4842.443119041285, 10098.14165795901, 10105.797884042278, 10106.696799096038, 10113.190944724405, 10119.24105692076, 5428.131498960148, 5430.15832545956, 5432.185172224119, 5434.728263586805, 5436.782528698887, 4915.09070821892, 4963.379577552806, 4974.950250497521, 4983.327250821165, 5017.046761115376, 3987.541432735273, 3998.7400818946676, 4040.252553277653, 4083.693722970789, 4136.875893723193, 1182.5860948667898, 1222.321611505623, 1277.7444511097817, 1288.7551365240574, 1306.3859985806655, 737.6385571070244, 783.5416281475286, 799.4523751230425, 911.1799380557419, 927.4945154890601, 5618.185363122325, 5984.311804142492, 6123.693815309306, 6124.908126378108, 6185.876282474415, 929.9256059515222, 931.6320053578934, 937.0395344771664, 941.7833898203452, 949.5632009275672, 3865.8646947356706, 4002.239574480839, 4064.309067458539, 4093.488313960755, 4113.115680128787, 10340.948295258475, 10714.142868570652, 10749.0, 10906.182036347305, 10941.015308622262, 9482.668106616235, 9923.851142232872, 10584.412338149185, 10717.9464102679, 10845.83731244032, 7351.264873512713, 7494.962525187339, 7773.144954055049, 7781.45529812919, 7781.610919454118, 5251.317328747238, 5438.673679579079, 6057.545684073801, 6107.786227482017, 6294.024426213944, 10258.58965641366, 10331.741706457398, 10344.224183186141, 10345.793084138426, 10368.030074285862, 9920.652777152765, 10318.638847640297, 10378.688639340831, 10425.478726063819, 10578.788424231627, 7653.6555854985545, 7722.806929826799, 7811.062672479339, 7821.843563128819, 7829.569373684502, 8418.536980466133, 8493.447925884797, 8499.702510412106, 8504.192101750325, 8514.574271286534, 6143.56995010345, 7506.211847755907, 7548.509346892434, 7557.584332861746, 7642.7325043623205, 7443.292887175709, 7473.850522989618, 7509.7371591994015, 7545.7358992435, 7572.242775722604, 7004.894926576004, 7014.438844892295, 7079.68141433647, 7092.326229008236, 7104.005439238407, 4588.105319462673, 4931.679440836334, 4967.776450059684, 5024.547790698854, 5097.362829646294, 5030.37120359955, 5060.468650791614, 5088.898222035613, 5104.41299250591, 5200.297959775382], "value": 4621.328783039293, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [251.69670546990872, 281.9661640603127, 287.5973636908328, 312.12604706820895, 317.27502656430033, 4483.7357134647, 5427.348718153827, 5463.535599474049, 5483.972580137099, 5513.8345849624575, 4332.0, 4467.106578684268, 4697.678136114774, 5398.055340315449, 5423.864403389917, 3617.305107694771, 3878.1855810279803, 4065.8170382332864, 4177.770222637754, 4232.470941132358, 3121.406932682783, 3591.7665351752185, 3858.151206734514, 4086.896537934756, 4139.3997870308785, 4085.4893138357706, 4103.8563650272245, 4113.416334711143, 4114.6091121343425, 4124.030852749616, 3323.0, 3539.6460353964526, 3781.2815565042556, 3945.6448919597374, 4008.118213992917, 3254.1213872254357, 3267.9183020424443, 3500.842462089212, 3515.874920025593, 3529.8235088245483, 3895.3572660511263, 4732.266498692708, 5122.694787345672, 5217.530422262014, 5224.216367544861, 4296.699231053827, 4606.170889239936, 4618.468876079261, 4650.906981860344, 4694.976525117352, 5205.817796377109, 5595.328560572717, 5651.943480565224, 5663.470862867044, 5745.683987380692, 3431.7083047940955, 3855.575886652466, 3858.84564617415, 3864.2271545690974, 3897.3569361055393, 4604.769761511944, 5706.77172913083, 5761.971190144064, 5863.648181109104, 5913.526917846562, 3824.101336185994, 3867.207222519377, 3882.514685664292, 3887.9805600971813, 3905.6484916357094, 3926.6858651307825, 3932.8820135395986, 3950.2889479893897, 3964.0, 3975.7216994810087, 4025.054112283611, 4470.485894122153, 4594.356790049395, 4602.424696912886, 4635.188841952645, 5633.605647604629, 5633.661980281115, 5659.292588426447, 5668.404817494186, 5675.3757086720425, 1151.583552230761, 1269.0418733856063, 1290.9354532273348, 1310.4103153580736, 1356.525216174331, 723.0419693905596, 785.0696924144906, 813.7151996801191, 824.2581676491205, 832.0099082092412, 5110.977804439052, 5113.054084994288, 5295.232191332312, 5297.470252974749, 5337.959097975935, 4855.5387238212315, 5324.201369794562, 5801.389374781365, 5820.679862607597, 5837.357890632027, 4234.9364759528635, 4932.988737308843, 5251.658642188246, 5284.207368894619, 5305.761240744176, 3008.916789955614, 3016.2610160510817, 3032.863521141554, 3034.908952731422, 3037.9367221472307, 5171.267625345551, 5637.661740295595, 5919.289685237799, 5964.463198312173, 5970.432808883149, 5198.402183748842, 5509.696966666792, 5938.524918006549, 6004.5496587755215, 6020.735645514404, 4687.343771871941, 5254.395744489356, 5673.489385955284, 6027.638341699512, 6105.595712986038, 5683.403242659627, 5755.194272801895, 5920.158324004416, 5928.288605367384, 5938.910916336172, 5278.709670968134, 5749.281339832521, 5750.281214848144, 5756.165356023437, 5821.970890145522, 5384.811531596375, 5551.833444996657, 5676.829695109153, 5798.739056742541, 6013.458788709037, 3282.4255755243216, 3634.855020668481, 3846.4614953907035, 3889.902752431212, 3892.2410130025505, 4691.765411729331, 5697.4302569742695, 5839.211706419579, 5899.056151015817, 5899.410058994238, 5116.309298244838, 5617.197296311595, 5700.344460386943, 5723.02708539541, 5766.59633825645, 4329.00432900441, 5453.645513041798, 5669.688167150846, 5677.716114194356, 5720.885582288414, 4578.88552786183, 5590.8043218487155, 5623.831285061455, 5663.688497132697, 5665.461781130786, 4690.108879312919, 5115.820946266863, 5531.419200984, 5692.886142277049, 5742.856428589319, 4189.748615082983, 5486.286782718381, 5632.802851900164, 5633.155026746021, 5636.5208957237455], "value": 4067.605114660283, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.9222689390721, 682.9351211634893, 692.2696555134332, 704.3132945378254, 744.8212429017034, 14867.107973521583, 15487.148206848618, 15977.600559986005, 15996.92001539992, 16237.431689890856, 14611.707765844676, 14613.0, 14794.852051479482, 14810.111393316409, 14862.925685371585, 665.7170702451463, 693.4694958356861, 821.6097353756977, 831.2892476932242, 851.7700220940343, 840.1136800675285, 846.3356265331715, 848.8769128476367, 848.9108643592426, 849.1932663969224, 897.6005677473527, 898.0794685447403, 898.7932775461647, 899.9415038022507, 900.3967341880956, 690.0339524665484, 839.7816567692413, 890.585877566928, 895.8432274351962, 898.4025622960723, 662.6256165266657, 664.0669858848325, 664.1067763857603, 664.6577012838392, 664.9534532582672, 14749.336279867324, 14784.11295322284, 14784.926075369554, 14787.40850365984, 14807.0, 13815.447382104703, 13905.374258158407, 13910.0, 13976.161430314214, 14169.29153542329, 12724.363781810873, 12775.936120319431, 12789.680257993532, 13023.283719395524, 13263.933680331536, 4561.270196768501, 4617.492075871652, 4759.976200118977, 4808.0383923215495, 4811.374521312242, 102720.43198920012, 111675.0, 113840.0, 114223.71552568903, 114631.0, 9843.052124660144, 11050.115990720884, 11085.778284434266, 11595.357078861873, 11684.831516848417, 21817.890910545346, 21851.235206768004, 21867.0, 21889.0, 21889.67165492519, 7778.8833167502535, 7877.7636670899965, 7932.524048557105, 7941.166177551389, 8002.399820013522, 3610.4403817408343, 4267.125239325931, 4311.33174357975, 4323.0, 4447.577480139382, 2966.569847372119, 3566.144125409883, 3595.1371670799344, 3599.586047604507, 3932.6628946158444, 558.5065415824075, 657.7174509706092, 740.5630677900065, 751.8270797716556, 752.785456144996, 549.1707521642348, 551.7792882846813, 606.5905513778182, 641.5893827950116, 682.2938258902012, 9381.249500039981, 9429.575669094907, 9547.2839537035, 9580.56887440053, 9588.328816982745, 625.343389441085, 639.8510315864538, 649.574528683713, 649.7173729427619, 650.0541711809307, 4581.335706322631, 4651.37206477128, 4653.232216684277, 4653.302004699254, 4663.0, 158105.62841557385, 163152.0, 172020.0, 173539.0, 176834.0, 59868.0, 103338.4499232497, 111109.0, 136080.31959840335, 147168.0, 102732.0, 103154.48422757989, 104890.0, 110222.28444009002, 111315.33027004602, 9655.330515907834, 9851.029794041076, 9856.21150307988, 9894.208463322773, 9898.851517227104, 50745.492545075555, 52013.0, 55866.72066639641, 57685.711571441876, 62188.0, 64071.0, 84510.0, 85876.0, 94931.52534237284, 99611.0, 13213.933930330288, 13474.595762127152, 13484.258365789976, 13843.515476958259, 13856.722865542431, 14840.925795370955, 14859.0, 14902.25488725581, 14926.477573284883, 15266.923665381602, 14023.088499247517, 14033.578992630237, 14059.507917222849, 14183.14901105937, 14550.199739014455, 12552.560660376843, 13030.804537931748, 13261.867381326449, 13282.0, 13348.666283342995, 13570.253636050113, 13787.51743688966, 13945.930270348583, 14120.435182592992, 14244.857551424768, 11362.431878405881, 11464.025557827677, 11611.187216894736, 11753.412329383667, 11956.163068585116, 10926.836097458703, 11049.226554141133, 11669.299842009506, 11672.941635291769, 11743.354115523729], "value": 7853.0966209349735, "unit": "score"}], "name": "dromaeo_css", "value": 7413.038852275342, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 114.0, 113.0, 113.0, 112.0, 113.0, 112.0, 112.0, 110.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 137.0, 135.0, 157.0, 106.0, 126.0, 129.0, 107.0, 160.0, 107.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 187.0, 182.0, 183.0, 180.0, 184.0, 181.0, 184.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 58.0, 108.0, 58.0, 57.0, 60.0, 62.0, 97.0, 56.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 95.0, 93.0, 71.0, 72.0, 70.0, 96.0, 73.0, 69.0, 113.0], "value": 83.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 87.0, 83.0, 88.0, 84.0, 88.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 94.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 92.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 142.0, 84.0, 83.0, 86.0, 84.0, 83.0, 92.0, 128.0, 86.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 55.0, 56.0, 53.0, 54.0, 53.0, 54.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 79.0, 76.0, 73.0, 70.0, 72.0, 72.0, 70.0, 75.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 82.0, 84.0, 82.0, 84.0, 84.0, 82.0, 86.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 136.0, 140.0, 138.0, 143.0, 141.0, 138.0, 194.0, 151.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 161.0, 163.0, 161.0, 163.0, 163.0, 162.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 67.0, 66.0, 67.0, 67.0, 66.0, 67.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1443.2, "alertThreshold": 2.0}]} 07:23:23 INFO - Return code: 0 07:23:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:23:23 INFO - # TBPL SUCCESS # 07:23:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:23:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:23:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:23:23 INFO - 2016-06-30 07:23:23.135 screenresolution[1667:9757] starting screenresolution argv=screenresolution get 07:23:23 INFO - 2016-06-30 07:23:23.137 screenresolution[1667:9757] Display 0: 1600x1200x32@60 07:23:23 INFO - 2016-06-30 07:23:23.143 screenresolution[1668:9760] starting screenresolution argv=screenresolution list 07:23:23 INFO - Available Modes on Display 0 07:23:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:23:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:23:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:23:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:23:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:23:23 INFO - Intel Iris: 07:23:23 INFO - Chipset Model: Intel Iris 07:23:23 INFO - Type: GPU 07:23:23 INFO - Bus: Built-In 07:23:23 INFO - VRAM (Dynamic, Max): 1536 MB 07:23:23 INFO - Vendor: Intel (0x8086) 07:23:23 INFO - Device ID: 0x0a2e 07:23:23 INFO - Revision ID: 0x0009 07:23:23 INFO - Displays: 07:23:23 INFO - Display: 07:23:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:23:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:23:23 INFO - Display Serial Number: 1600x1200 60 07:23:23 INFO - Main Display: Yes 07:23:23 INFO - Mirror: Off 07:23:23 INFO - Online: Yes 07:23:23 INFO - Rotation: Supported 07:23:23 INFO - Return code: 0 07:23:23 INFO - Running post-action listener: _resource_record_post_action 07:23:23 INFO - ##### 07:23:23 INFO - ##### Finished run-tests step (success) 07:23:23 INFO - ##### 07:23:23 INFO - Running post-run listener: _resource_record_post_run 07:23:23 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37111296; Write bytes: 703535616; Read time: 239; Write time: 1935 07:23:23 INFO - TinderboxPrint: CPU usage
53.3% 07:23:23 INFO - TinderboxPrint: I/O read bytes / time
37,111,296 / 239 07:23:23 INFO - TinderboxPrint: I/O write bytes / time
703,535,616 / 1,935 07:23:23 INFO - TinderboxPrint: CPU idle
2,263.3 (46.7%) 07:23:23 INFO - TinderboxPrint: CPU system
965.6 (19.9%) 07:23:23 INFO - TinderboxPrint: CPU user
1,616.9 (33.4%) 07:23:23 INFO - TinderboxPrint: Swap in / out
443,518,976 / 0 07:23:23 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396382720; Write bytes: 390166016; Read time: 29319; Write time: 804 07:23:23 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 35046912; Write bytes: 293524480; Read time: 218; Write time: 1084 07:23:23 INFO - Running post-run listener: _upload_blobber_files 07:23:23 INFO - Blob upload gear active. 07:23:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:23:23 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 07:23:23 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'] 07:23:23 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 07:23:24 INFO - (blobuploader) - INFO - Open directory for files ... 07:23:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:23:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:23:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:25 INFO - (blobuploader) - INFO - Done attempting. 07:23:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:23:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:23:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:26 INFO - (blobuploader) - INFO - Done attempting. 07:23:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:23:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:23:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:29 INFO - (blobuploader) - INFO - Done attempting. 07:23:29 INFO - (blobuploader) - INFO - Iteration through files over. 07:23:29 INFO - Return code: 0 07:23:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:23:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:23:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21a0a445e04d1e4d04ccad1fa75f2c5674cd8acde4ff5fbd10afce65a98419b6272826e31cb38353cdd6e817dab840e9f41d537e23163ebc5601dad7c12785c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf18e93ced42fac6ce5820e66cce0ee46513f4489e71dc961b49552b8bf885c92e0c498c713204d8ccb417288bc0407c02ab3dfb14cd1fa44efe6dc716905602", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7bd1e6aed0dffd9928882f6fb7a10c0e4a315b1cec52181761a46b017511fa6054eab1cc890edec148f12db3780f956908eeea252c9b1ffa8209f7f2ff57da8"} 07:23:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:23:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:23:29 INFO - Contents: 07:23:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/21a0a445e04d1e4d04ccad1fa75f2c5674cd8acde4ff5fbd10afce65a98419b6272826e31cb38353cdd6e817dab840e9f41d537e23163ebc5601dad7c12785c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf18e93ced42fac6ce5820e66cce0ee46513f4489e71dc961b49552b8bf885c92e0c498c713204d8ccb417288bc0407c02ab3dfb14cd1fa44efe6dc716905602", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7bd1e6aed0dffd9928882f6fb7a10c0e4a315b1cec52181761a46b017511fa6054eab1cc890edec148f12db3780f956908eeea252c9b1ffa8209f7f2ff57da8"} 07:23:29 INFO - Running post-run listener: copy_logs_to_upload_dir 07:23:29 INFO - Copying logs to upload dir... 07:23:29 INFO - mkdir: /builds/slave/test/build/upload/logs 07:23:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.728210 ========= master_lag: 0.66 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-06-30 07:23:29.984797) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 07:23:29.988643) ========= 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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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/21a0a445e04d1e4d04ccad1fa75f2c5674cd8acde4ff5fbd10afce65a98419b6272826e31cb38353cdd6e817dab840e9f41d537e23163ebc5601dad7c12785c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf18e93ced42fac6ce5820e66cce0ee46513f4489e71dc961b49552b8bf885c92e0c498c713204d8ccb417288bc0407c02ab3dfb14cd1fa44efe6dc716905602", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7bd1e6aed0dffd9928882f6fb7a10c0e4a315b1cec52181761a46b017511fa6054eab1cc890edec148f12db3780f956908eeea252c9b1ffa8209f7f2ff57da8"} build_url:https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/NpMNYwgZS8G35sJHZ44LoA/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/21a0a445e04d1e4d04ccad1fa75f2c5674cd8acde4ff5fbd10afce65a98419b6272826e31cb38353cdd6e817dab840e9f41d537e23163ebc5601dad7c12785c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cf18e93ced42fac6ce5820e66cce0ee46513f4489e71dc961b49552b8bf885c92e0c498c713204d8ccb417288bc0407c02ab3dfb14cd1fa44efe6dc716905602", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7bd1e6aed0dffd9928882f6fb7a10c0e4a315b1cec52181761a46b017511fa6054eab1cc890edec148f12db3780f956908eeea252c9b1ffa8209f7f2ff57da8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 07:23:30.022134) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:23:30.022496) ========= 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.uRUZaQHVJq/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.TNREl5Vjgw/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.004659 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 07:23:30.071697) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 07:23:30.072116) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 07:23:30.072586) ========= ========= Total master_lag: 0.80 =========