builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0289 starttime: 1467651600.29 results: success (0) buildid: 20160704073634 builduid: b0822160d41d422fa43a587c8149130f revision: 93cc9bcee8c88643cec1961884fc5750edfd57d3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:00.293891) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:00.294330) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:00.294608) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:00.486035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:00.486395) ========= 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.Ye42LVof2X/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.kl4aeK3DFq/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-04 10:00:00-- 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% 181M=0s 2016-07-04 10:00:01 (181 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.508112 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:01.210502) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:01.215017) ========= 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.Ye42LVof2X/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.kl4aeK3DFq/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.030907 ========= master_lag: 0.24 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:01.481997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-04 10:00:01.482402) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 93cc9bcee8c88643cec1961884fc5750edfd57d3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 93cc9bcee8c88643cec1961884fc5750edfd57d3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ye42LVof2X/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.kl4aeK3DFq/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-04 10:00:01,602 truncating revision to first 12 chars 2016-07-04 10:00:01,602 Setting DEBUG logging. 2016-07-04 10:00:01,602 attempt 1/10 2016-07-04 10:00:01,602 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/93cc9bcee8c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 10:00:03,514 unpacking tar archive at: autoland-93cc9bcee8c8/testing/mozharness/ program finished with exit code 0 elapsedTime=2.187207 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-04 10:00:03.726791) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:03.727092) ========= script_repo_revision: 93cc9bcee8c88643cec1961884fc5750edfd57d3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:03.727569) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:03.727848) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 10:00:03.746407) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-07-04 10:00:03.746778) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ye42LVof2X/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.kl4aeK3DFq/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:00:03 INFO - MultiFileLogger online at 20160704 10:00:03 in /builds/slave/test 10:00:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:00:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:00:03 INFO - {'append_to_log': False, 10:00:03 INFO - 'base_work_dir': '/builds/slave/test', 10:00:03 INFO - 'blob_upload_branch': 'Autoland', 10:00:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:00:03 INFO - 'branch': 'Autoland', 10:00:03 INFO - 'buildbot_json_path': 'buildprops.json', 10:00:03 INFO - 'config_files': ('talos/mac_config.py',), 10:00:03 INFO - 'default_actions': ('clobber', 10:00:03 INFO - 'read-buildbot-config', 10:00:03 INFO - 'download-and-extract', 10:00:03 INFO - 'populate-webroot', 10:00:03 INFO - 'create-virtualenv', 10:00:03 INFO - 'install', 10:00:03 INFO - 'run-tests'), 10:00:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:00:03 INFO - 'download_minidump_stackwalk': True, 10:00:03 INFO - 'download_symbols': 'ondemand', 10:00:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:00:03 INFO - 'tooltool.py': '/tools/tooltool.py', 10:00:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:00:03 INFO - '/tools/misc-python/virtualenv.py')}, 10:00:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:00:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:00:03 INFO - 'installer_path': 'installer.exe', 10:00:03 INFO - 'log_level': 'info', 10:00:03 INFO - 'log_name': 'talos', 10:00:03 INFO - 'log_to_console': True, 10:00:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:00:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:00:03 INFO - 'opt_config_files': (), 10:00:03 INFO - 'pip_index': False, 10:00:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:00:03 INFO - 'cmd': ('bash', 10:00:03 INFO - '-c', 10:00:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:00:03 INFO - 'enabled': True, 10:00:03 INFO - 'halt_on_failure': False, 10:00:03 INFO - 'name': 'check_screen_resolution'},), 10:00:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:00:03 INFO - 'cmd': ('bash', 10:00:03 INFO - '-c', 10:00:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:00:03 INFO - 'enabled': True, 10:00:03 INFO - 'halt_on_failure': False, 10:00:03 INFO - 'name': 'check_screen_resolution'},), 10:00:03 INFO - 'run_cmd_checks_enabled': True, 10:00:03 INFO - 'sps_profile': False, 10:00:03 INFO - 'sps_profile_interval': 0, 10:00:03 INFO - 'suite': 'dromaeojs', 10:00:03 INFO - 'system_bits': '32', 10:00:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:00:03 INFO - 'title': 't-yosemite-r7-0289', 10:00:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:00:03 INFO - 'use_talos_json': True, 10:00:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:00:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:00:03 INFO - 'work_dir': 'build'} 10:00:03 INFO - ##### 10:00:03 INFO - ##### Running clobber step. 10:00:03 INFO - ##### 10:00:03 INFO - Running pre-action listener: _resource_record_pre_action 10:00:03 INFO - Running main action method: clobber 10:00:03 INFO - rmtree: /builds/slave/test/build 10:00:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:00:05 INFO - Running post-action listener: _resource_record_post_action 10:00:05 INFO - ##### 10:00:05 INFO - ##### Finished clobber step (success) 10:00:05 INFO - ##### 10:00:05 INFO - ##### 10:00:05 INFO - ##### Running read-buildbot-config step. 10:00:05 INFO - ##### 10:00:05 INFO - Running pre-action listener: _resource_record_pre_action 10:00:05 INFO - Running main action method: read_buildbot_config 10:00:05 INFO - Using buildbot properties: 10:00:05 INFO - { 10:00:05 INFO - "project": "", 10:00:05 INFO - "product": "firefox", 10:00:05 INFO - "script_repo_revision": "production", 10:00:05 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 10:00:05 INFO - "repository": "", 10:00:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 10:00:05 INFO - "buildid": "20160704073634", 10:00:05 INFO - "pgo_build": "False", 10:00:05 INFO - "basedir": "/builds/slave/test", 10:00:05 INFO - "buildnumber": 4, 10:00:05 INFO - "slavename": "t-yosemite-r7-0289", 10:00:05 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:00:05 INFO - "platform": "yosemite_r7", 10:00:05 INFO - "scheduler": "tests-autoland-macosx64-talos", 10:00:05 INFO - "branch": "autoland", 10:00:05 INFO - "repo_path": "integration/autoland", 10:00:05 INFO - "revision": "93cc9bcee8c88643cec1961884fc5750edfd57d3", 10:00:05 INFO - "stage_platform": "macosx64", 10:00:05 INFO - "builduid": "b0822160d41d422fa43a587c8149130f", 10:00:05 INFO - "slavebuilddir": "test" 10:00:05 INFO - } 10:00:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:00:05 INFO - Running post-action listener: _resource_record_post_action 10:00:05 INFO - ##### 10:00:05 INFO - ##### Finished read-buildbot-config step (success) 10:00:05 INFO - ##### 10:00:05 INFO - ##### 10:00:05 INFO - ##### Running download-and-extract step. 10:00:05 INFO - ##### 10:00:05 INFO - Running pre-action listener: _resource_record_pre_action 10:00:05 INFO - Running main action method: download_and_extract 10:00:05 INFO - mkdir: /builds/slave/test/build/tests 10:00: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')]} 10:00:05 INFO - https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:00:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:00:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:00:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/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 10:00:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/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 10:00:13 INFO - Downloaded 1199 bytes. 10:00:13 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:00:13 INFO - Using the following test package requirements: 10:00:13 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:00:13 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:00:13 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:00:13 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:00:13 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:00:13 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:00:13 INFO - u'jsshell-mac.zip'], 10:00:13 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:00:13 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:00:13 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:00:13 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:00:13 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:00:13 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:00:13 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:00:13 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:00:13 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:00:13 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:00:13 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:00:13 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:13 INFO - https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:00:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:00:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:00:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/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 10:00:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/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 10:00:20 INFO - Downloaded 18795249 bytes. 10:00:20 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'] 10:00:20 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 10:00:23 INFO - Return code: 0 10:00:23 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 10:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:23 INFO - https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:00:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:00:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:00:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/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 10:00:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/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 10:00:31 INFO - Downloaded 18795249 bytes. 10:00:31 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'] 10:00:31 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 10:00:34 INFO - Return code: 0 10:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:34 INFO - https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:00:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:00:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:00:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/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 10:00:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/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 10:00:35 INFO - Downloaded 14403333 bytes. 10:00:35 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'] 10:00:35 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 10:00:36 INFO - Return code: 0 10:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:36 INFO - https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:00:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:00:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:00:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:00:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PYxnPatPRCeFWGXHC2gm7g/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 10:00:41 INFO - Downloaded 112413770 bytes. 10:00:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:00:41 INFO - mkdir: /builds/slave/test/properties 10:00:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:00:41 INFO - Writing to file /builds/slave/test/properties/build_url 10:00:41 INFO - Contents: 10:00:41 INFO - build_url:https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:00:42 INFO - Running post-action listener: _resource_record_post_action 10:00:42 INFO - Running post-action listener: set_extra_try_arguments 10:00:42 INFO - ##### 10:00:42 INFO - ##### Finished download-and-extract step (success) 10:00:42 INFO - ##### 10:00:42 INFO - ##### 10:00:42 INFO - ##### Running populate-webroot step. 10:00:42 INFO - ##### 10:00:42 INFO - Running pre-action listener: _resource_record_pre_action 10:00:42 INFO - Running main action method: populate_webroot 10:00:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:00:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:00:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:00:42 INFO - '--tppagecycles', 10:00:42 INFO - '7'], 10:00:42 INFO - 'tests': ['tsvgm']}}, 10:00:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:00:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:00:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:00:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:00:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:00:42 INFO - 'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:00:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:00:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:00:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:00:42 INFO - 'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['damp', 'tps']}, 10:00:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:00:42 INFO - 'tests': ['damp', 'tps']}, 10:00:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['dromaeo_dom']}, 10:00:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:00:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['basic_compositor_video']}, 10:00:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:00:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['a11yr', 10:00:42 INFO - 'ts_paint', 10:00:42 INFO - 'tpaint', 10:00:42 INFO - 'sessionrestore', 10:00:42 INFO - 'sessionrestore_no_auto_restore', 10:00:42 INFO - 'tabpaint']}, 10:00:42 INFO - 'other-e10s': {'tests': ['a11yr', 10:00:42 INFO - 'ts_paint', 10:00:42 INFO - 'tpaint', 10:00:42 INFO - 'sessionrestore', 10:00:42 INFO - 'sessionrestore_no_auto_restore', 10:00:42 INFO - 'tabpaint']}, 10:00:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:00:42 INFO - 'ts_paint', 10:00:42 INFO - 'tpaint', 10:00:42 INFO - 'sessionrestore', 10:00:42 INFO - 'sessionrestore_no_auto_restore', 10:00:42 INFO - 'tabpaint']}, 10:00:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:00:42 INFO - 'ts_paint', 10:00:42 INFO - 'tpaint', 10:00:42 INFO - 'sessionrestore', 10:00:42 INFO - 'sessionrestore_no_auto_restore', 10:00:42 INFO - 'tabpaint']}, 10:00:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['a11yr', 10:00:42 INFO - 'ts_paint', 10:00:42 INFO - 'tpaint', 10:00:42 INFO - 'sessionrestore', 10:00:42 INFO - 'sessionrestore_no_auto_restore', 10:00:42 INFO - 'tabpaint']}, 10:00:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['a11yr', 10:00:42 INFO - 'ts_paint', 10:00:42 INFO - 'tpaint', 10:00:42 INFO - 'sessionrestore', 10:00:42 INFO - 'sessionrestore_no_auto_restore', 10:00:42 INFO - 'tabpaint']}, 10:00:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['tsvgx', 10:00:42 INFO - 'tsvgr_opacity', 10:00:42 INFO - 'tart', 10:00:42 INFO - 'tscrollx', 10:00:42 INFO - 'cart']}, 10:00:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:00:42 INFO - 'tsvgr_opacity', 10:00:42 INFO - 'tart', 10:00:42 INFO - 'tscrollx', 10:00:42 INFO - 'cart']}, 10:00:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:00:42 INFO - 'talos_options': ['--disable-e10s'], 10:00:42 INFO - 'tests': ['tp5o']}, 10:00:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:00:42 INFO - 'tests': ['tp5o']}, 10:00:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:00:42 INFO - 'talos_options': ['--disable-e10s', 10:00:42 INFO - '--xperf_path', 10:00:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:00:42 INFO - 'tests': ['tp5n']}, 10:00:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:00:42 INFO - 'talos_options': ['--xperf_path', 10:00:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:00:42 INFO - 'tests': ['tp5n']}}, 10:00:42 INFO - 'talos.zip': {'path': '', 10:00:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:00:42 INFO - Running post-action listener: _resource_record_post_action 10:00:42 INFO - ##### 10:00:42 INFO - ##### Finished populate-webroot step (success) 10:00:42 INFO - ##### 10:00:42 INFO - ##### 10:00:42 INFO - ##### Running create-virtualenv step. 10:00:42 INFO - ##### 10:00:42 INFO - Running pre-action listener: _resource_record_pre_action 10:00:42 INFO - Running main action method: create_virtualenv 10:00:42 INFO - Creating virtualenv /builds/slave/test/build/venv 10:00:42 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 10:00:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:00:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:00:42 INFO - Using real prefix '/tools/python27' 10:00:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:00:43 INFO - Installing distribute.............................................................................................................................................................................................done. 10:00:46 INFO - Installing pip.................done. 10:00:46 INFO - Return code: 0 10:00:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:00: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')]} 10:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00: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')]} 10:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:46 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 0x106ea0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff65aca62d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, '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.Ye42LVof2X/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.kl4aeK3DFq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:00:46 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 10:00:46 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 10:00:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:00:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:46 INFO - 'HOME': '/Users/cltbld', 10:00:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:46 INFO - 'LOGNAME': 'cltbld', 10:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:46 INFO - 'MOZ_NO_REMOTE': '1', 10:00:46 INFO - 'NO_EM_RESTART': '1', 10:00:46 INFO - 'PAGER': '/bin/cat', 10:00:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:46 INFO - 'PWD': '/builds/slave/test', 10:00:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:46 INFO - 'SHELL': '/bin/bash', 10:00:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:00:46 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:00:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:46 INFO - 'USER': 'cltbld', 10:00:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:46 INFO - 'XPC_FLAGS': '0x0', 10:00:46 INFO - 'XPC_SERVICE_NAME': '0', 10:00:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:46 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 10:00:46 INFO - Cleaning up... 10:00:46 INFO - Return code: 0 10:00:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:00: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')]} 10:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00: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')]} 10:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:46 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 0x106ea0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff65aca62d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, '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.Ye42LVof2X/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.kl4aeK3DFq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:00:46 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 10:00:46 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 10:00:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:00:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:46 INFO - 'HOME': '/Users/cltbld', 10:00:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:46 INFO - 'LOGNAME': 'cltbld', 10:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:46 INFO - 'MOZ_NO_REMOTE': '1', 10:00:46 INFO - 'NO_EM_RESTART': '1', 10:00:46 INFO - 'PAGER': '/bin/cat', 10:00:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:46 INFO - 'PWD': '/builds/slave/test', 10:00:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:46 INFO - 'SHELL': '/bin/bash', 10:00:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:00:46 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:00:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:46 INFO - 'USER': 'cltbld', 10:00:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:46 INFO - 'XPC_FLAGS': '0x0', 10:00:46 INFO - 'XPC_SERVICE_NAME': '0', 10:00:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:46 INFO - Downloading/unpacking psutil>=3.1.1 10:00:46 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 10:00:46 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 10:00:46 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 10:00:46 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 10:00:46 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 10:00:46 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 10:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:00:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:00:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:00:50 INFO - Installing collected packages: psutil 10:00:50 INFO - Running setup.py install for psutil 10:00:50 INFO - building 'psutil._psutil_osx' extension 10:00:50 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 10:00:50 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 10:00:50 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 10:00:50 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 10:00:50 INFO - building 'psutil._psutil_posix' extension 10:00:50 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 10:00:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:00:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:00:51 INFO - ^ 10:00:51 INFO - 1 warning generated. 10:00:51 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 10:00:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:00:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:00:51 INFO - Successfully installed psutil 10:00:51 INFO - Cleaning up... 10:00:51 INFO - Return code: 0 10:00:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:00:51 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')]} 10:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:51 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')]} 10:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:51 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 0x106ea0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff65aca62d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, '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.Ye42LVof2X/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.kl4aeK3DFq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:00:51 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 10:00:51 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 10:00:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:00:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:51 INFO - 'HOME': '/Users/cltbld', 10:00:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:51 INFO - 'LOGNAME': 'cltbld', 10:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:51 INFO - 'MOZ_NO_REMOTE': '1', 10:00:51 INFO - 'NO_EM_RESTART': '1', 10:00:51 INFO - 'PAGER': '/bin/cat', 10:00:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:51 INFO - 'PWD': '/builds/slave/test', 10:00:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:51 INFO - 'SHELL': '/bin/bash', 10:00:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:00:51 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:00:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:51 INFO - 'USER': 'cltbld', 10:00:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:51 INFO - 'XPC_FLAGS': '0x0', 10:00:51 INFO - 'XPC_SERVICE_NAME': '0', 10:00:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:00:51 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 10:00:51 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 10:00:51 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 10:00:51 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 10:00:51 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 10:00:51 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 10:00:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:00:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:00:54 INFO - Installing collected packages: mozsystemmonitor 10:00:54 INFO - Running setup.py install for mozsystemmonitor 10:00:54 INFO - Successfully installed mozsystemmonitor 10:00:54 INFO - Cleaning up... 10:00:54 INFO - Return code: 0 10:00:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:00:54 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')]} 10:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:54 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')]} 10:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:54 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 0x106ea0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff65aca62d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, '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.Ye42LVof2X/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.kl4aeK3DFq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:00:54 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 10:00:54 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 10:00:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:00:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:54 INFO - 'HOME': '/Users/cltbld', 10:00:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:54 INFO - 'LOGNAME': 'cltbld', 10:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:54 INFO - 'MOZ_NO_REMOTE': '1', 10:00:54 INFO - 'NO_EM_RESTART': '1', 10:00:54 INFO - 'PAGER': '/bin/cat', 10:00:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:54 INFO - 'PWD': '/builds/slave/test', 10:00:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:54 INFO - 'SHELL': '/bin/bash', 10:00:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:00:54 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:00:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:54 INFO - 'USER': 'cltbld', 10:00:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:54 INFO - 'XPC_FLAGS': '0x0', 10:00:54 INFO - 'XPC_SERVICE_NAME': '0', 10:00:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:54 INFO - Downloading/unpacking jsonschema==2.5.1 10:00:54 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 10:00:54 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 10:00:54 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 10:00:54 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 10:00:54 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 10:00:54 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 10:00:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:00:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:57 INFO - Downloading functools32-3.2.3-2.tar.gz 10:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:00:57 INFO - warning: no files found matching '*.txt' 10:00:57 INFO - no previously-included directories found matching 'build' 10:00:57 INFO - no previously-included directories found matching 'dist' 10:00:57 INFO - no previously-included directories found matching '.git*' 10:00:57 INFO - Installing collected packages: jsonschema, functools32 10:00:57 INFO - Running setup.py install for functools32 10:00:57 INFO - warning: no files found matching '*.txt' 10:00:57 INFO - no previously-included directories found matching 'build' 10:00:57 INFO - no previously-included directories found matching 'dist' 10:00:57 INFO - no previously-included directories found matching '.git*' 10:00:57 INFO - Successfully installed jsonschema functools32 10:00:57 INFO - Cleaning up... 10:00:58 INFO - Return code: 0 10:00:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:00:58 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 0x106ea0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff65aca62d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, '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.Ye42LVof2X/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.kl4aeK3DFq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:00:58 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 10:00:58 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 10:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:58 INFO - 'HOME': '/Users/cltbld', 10:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:58 INFO - 'LOGNAME': 'cltbld', 10:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:58 INFO - 'MOZ_NO_REMOTE': '1', 10:00:58 INFO - 'NO_EM_RESTART': '1', 10:00:58 INFO - 'PAGER': '/bin/cat', 10:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:58 INFO - 'PWD': '/builds/slave/test', 10:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:58 INFO - 'SHELL': '/bin/bash', 10:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:00:58 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:58 INFO - 'USER': 'cltbld', 10:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:58 INFO - 'XPC_FLAGS': '0x0', 10:00:58 INFO - 'XPC_SERVICE_NAME': '0', 10:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:58 INFO - Downloading/unpacking blobuploader==1.2.4 10:00:58 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 10:00:58 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 10:00:58 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 10:00:58 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 10:00:58 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 10:00:58 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 10:01:00 INFO - Downloading blobuploader-1.2.4.tar.gz 10:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:01:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:01:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:01 INFO - Downloading docopt-0.6.1.tar.gz 10:01:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:01:02 INFO - Installing collected packages: blobuploader, requests, docopt 10:01:02 INFO - Running setup.py install for blobuploader 10:01:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:01:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:01:02 INFO - Running setup.py install for requests 10:01:02 INFO - Running setup.py install for docopt 10:01:02 INFO - Successfully installed blobuploader requests docopt 10:01:02 INFO - Cleaning up... 10:01:02 INFO - Return code: 0 10:01:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:02 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 0x106ea0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff65aca62d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, '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.Ye42LVof2X/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.kl4aeK3DFq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:01:02 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 10:01:02 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 10:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:02 INFO - 'HOME': '/Users/cltbld', 10:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:02 INFO - 'LOGNAME': 'cltbld', 10:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:02 INFO - 'MOZ_NO_REMOTE': '1', 10:01:02 INFO - 'NO_EM_RESTART': '1', 10:01:02 INFO - 'PAGER': '/bin/cat', 10:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:02 INFO - 'PWD': '/builds/slave/test', 10:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:02 INFO - 'SHELL': '/bin/bash', 10:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:01:02 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:02 INFO - 'USER': 'cltbld', 10:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:02 INFO - 'XPC_FLAGS': '0x0', 10:01:02 INFO - 'XPC_SERVICE_NAME': '0', 10:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:01:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-AWqTR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:01:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ikaTlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:01:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ky9T5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:01:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-3qR8Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:01:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-1JrqGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:01:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-dWGNKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:01:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-1pQXhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:01:03 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-TLTSFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:01:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-QYdiO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:01:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-wwkm5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:01:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-qFEXQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:01:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-vmapIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:01:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-g6KrxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:01:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-C9Dx3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-pQEaH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:01:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Flj0KW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:01:04 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-nPH_SP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:01:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:01:04 INFO - Running setup.py install for manifestparser 10:01:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:01:05 INFO - Running setup.py install for mozcrash 10:01:05 INFO - Running setup.py install for mozdebug 10:01:05 INFO - Running setup.py install for mozdevice 10:01:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:01:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:01:05 INFO - Running setup.py install for mozfile 10:01:05 INFO - Running setup.py install for mozhttpd 10:01:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:01:05 INFO - Running setup.py install for mozinfo 10:01:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Running setup.py install for mozInstall 10:01:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Running setup.py install for mozleak 10:01:06 INFO - Running setup.py install for mozlog 10:01:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Running setup.py install for moznetwork 10:01:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Running setup.py install for mozprocess 10:01:06 INFO - Running setup.py install for mozprofile 10:01:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:01:06 INFO - Running setup.py install for mozrunner 10:01:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:01:07 INFO - Running setup.py install for mozscreenshot 10:01:07 INFO - Running setup.py install for moztest 10:01:07 INFO - Running setup.py install for mozversion 10:01:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:01:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:01:07 INFO - Cleaning up... 10:01:07 INFO - Return code: 0 10:01:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01:07 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')]} 10:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:08 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 0x106ea0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff65aca62d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, '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.Ye42LVof2X/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.kl4aeK3DFq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:01:08 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 10:01:08 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 10:01:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:01:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:08 INFO - 'HOME': '/Users/cltbld', 10:01:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:08 INFO - 'LOGNAME': 'cltbld', 10:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:08 INFO - 'MOZ_NO_REMOTE': '1', 10:01:08 INFO - 'NO_EM_RESTART': '1', 10:01:08 INFO - 'PAGER': '/bin/cat', 10:01:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:08 INFO - 'PWD': '/builds/slave/test', 10:01:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:08 INFO - 'SHELL': '/bin/bash', 10:01:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:01:08 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:01:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:08 INFO - 'USER': 'cltbld', 10:01:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:08 INFO - 'XPC_FLAGS': '0x0', 10:01:08 INFO - 'XPC_SERVICE_NAME': '0', 10:01:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:01:08 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-VabOcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:01:08 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)) 10:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:01:08 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-UgxJ6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:01:08 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)) 10:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:01:08 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-JSugpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:01:08 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)) 10:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:01:08 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-RZoopp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:01:08 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)) 10:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:01:08 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-uNhAqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:01:08 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)) 10:01:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:01:08 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-ZJv2F2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-I7dhfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-wQVYLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-9wKF7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-C10L0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-TwgWX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-6_k8Ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Yim5hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-Ixhp0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-br6g_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:09 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)) 10:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:01:09 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-UQ9S4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:01:10 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)) 10:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:01:10 INFO - Running setup.py (path:/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/pip-CjUXaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:01:10 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)) 10:01:10 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)) 10:01:10 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)) 10:01:10 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)) 10:01:10 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)) 10:01:10 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)) 10:01:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:12 INFO - Downloading blessings-1.6.tar.gz 10:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:01:12 INFO - Installing collected packages: blessings 10:01:12 INFO - Running setup.py install for blessings 10:01:12 INFO - Successfully installed blessings 10:01:12 INFO - Cleaning up... 10:01:13 INFO - Return code: 0 10:01:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:01:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:01:13 INFO - Reading from file tmpfile_stdout 10:01:13 INFO - Current package versions: 10:01:13 INFO - blessings == 1.6 10:01:13 INFO - blobuploader == 1.2.4 10:01:13 INFO - docopt == 0.6.1 10:01:13 INFO - functools32 == 3.2.3-2 10:01:13 INFO - jsonschema == 2.5.1 10:01:13 INFO - manifestparser == 1.1 10:01:13 INFO - mozInstall == 1.12 10:01:13 INFO - mozcrash == 0.17 10:01:13 INFO - mozdebug == 0.1 10:01:13 INFO - mozdevice == 0.48 10:01:13 INFO - mozfile == 1.2 10:01:13 INFO - mozhttpd == 0.7 10:01:13 INFO - mozinfo == 0.9 10:01:13 INFO - mozleak == 0.1 10:01:13 INFO - mozlog == 3.2 10:01:13 INFO - moznetwork == 0.27 10:01:13 INFO - mozprocess == 0.22 10:01:13 INFO - mozprofile == 0.28 10:01:13 INFO - mozrunner == 6.11 10:01:13 INFO - mozscreenshot == 0.1 10:01:13 INFO - mozsystemmonitor == 0.3 10:01:13 INFO - moztest == 0.7 10:01:13 INFO - mozversion == 1.4 10:01:13 INFO - psutil == 3.1.1 10:01:13 INFO - requests == 1.2.3 10:01:13 INFO - wsgiref == 0.1.2 10:01:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:13 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 0x106ea0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff65aca62d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, '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.Ye42LVof2X/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.kl4aeK3DFq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:01:13 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 10:01:13 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 10:01:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:01:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:13 INFO - 'HOME': '/Users/cltbld', 10:01:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:13 INFO - 'LOGNAME': 'cltbld', 10:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:13 INFO - 'MOZ_NO_REMOTE': '1', 10:01:13 INFO - 'NO_EM_RESTART': '1', 10:01:13 INFO - 'PAGER': '/bin/cat', 10:01:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:13 INFO - 'PWD': '/builds/slave/test', 10:01:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:13 INFO - 'SHELL': '/bin/bash', 10:01:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:01:13 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:01:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:13 INFO - 'USER': 'cltbld', 10:01:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:13 INFO - 'XPC_FLAGS': '0x0', 10:01:13 INFO - 'XPC_SERVICE_NAME': '0', 10:01:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:13 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)) 10:01:13 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)) 10:01:13 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)) 10:01:13 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)) 10:01:13 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)) 10:01:13 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)) 10:01:13 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)) 10:01:13 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)) 10:01:13 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)) 10:01:13 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)) 10:01:13 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)) 10:01:13 INFO - Cleaning up... 10:01:13 INFO - Return code: 0 10:01:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:01:13 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 0x106ea0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f35618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff65aca62d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, '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.Ye42LVof2X/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.kl4aeK3DFq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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 10:01:13 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 10:01:13 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 10:01:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:01:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:13 INFO - 'HOME': '/Users/cltbld', 10:01:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:13 INFO - 'LOGNAME': 'cltbld', 10:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:13 INFO - 'MOZ_NO_REMOTE': '1', 10:01:13 INFO - 'NO_EM_RESTART': '1', 10:01:13 INFO - 'PAGER': '/bin/cat', 10:01:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:13 INFO - 'PWD': '/builds/slave/test', 10:01:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:13 INFO - 'SHELL': '/bin/bash', 10:01:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:01:13 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:01:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:13 INFO - 'USER': 'cltbld', 10:01:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:13 INFO - 'XPC_FLAGS': '0x0', 10:01:13 INFO - 'XPC_SERVICE_NAME': '0', 10:01:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:01:13 INFO - Cleaning up... 10:01:13 INFO - Return code: 0 10:01:13 INFO - Running post-action listener: _resource_record_post_action 10:01:13 INFO - Running post-action listener: _start_resource_monitoring 10:01:13 INFO - Starting resource monitoring. 10:01:13 INFO - ##### 10:01:13 INFO - ##### Finished create-virtualenv step (success) 10:01:13 INFO - ##### 10:01:13 INFO - ##### 10:01:13 INFO - ##### Running install step. 10:01:13 INFO - ##### 10:01:13 INFO - Running pre-action listener: _resource_record_pre_action 10:01:13 INFO - Running main action method: install 10:01:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:01:14 INFO - Reading from file tmpfile_stdout 10:01:14 INFO - Detecting whether we're running mozinstall >=1.0... 10:01:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:01:14 INFO - Reading from file tmpfile_stdout 10:01:14 INFO - Output received: 10:01:14 INFO - Usage: mozinstall [options] installer 10:01:14 INFO - Options: 10:01:14 INFO - -h, --help show this help message and exit 10:01:14 INFO - -d DEST, --destination=DEST 10:01:14 INFO - Directory to install application into. [default: 10:01:14 INFO - "/builds/slave/test"] 10:01:14 INFO - --app=APP Application being installed. [default: firefox] 10:01:14 INFO - mkdir: /builds/slave/test/build/application 10:01:14 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'] 10:01:14 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 10:01:48 INFO - Reading from file tmpfile_stdout 10:01:48 INFO - Output received: 10:01:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:01:48 INFO - Running post-action listener: _resource_record_post_action 10:01:48 INFO - ##### 10:01:48 INFO - ##### Finished install step (success) 10:01:48 INFO - ##### 10:01:48 INFO - ##### 10:01:48 INFO - ##### Running run-tests step. 10:01:48 INFO - ##### 10:01:48 INFO - Running pre-action listener: _resource_record_pre_action 10:01:48 INFO - Running main action method: run_tests 10:01:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:01:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:01:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:01:48 INFO - 2016-07-04 10:01:48.969 screenresolution[1649:8330] starting screenresolution argv=screenresolution get 10:01:48 INFO - 2016-07-04 10:01:48.972 screenresolution[1649:8330] Display 0: 1600x1200x32@60 10:01:48 INFO - 2016-07-04 10:01:48.977 screenresolution[1650:8332] starting screenresolution argv=screenresolution list 10:01:48 INFO - Available Modes on Display 0 10:01:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:01:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:01:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:01:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:01:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:01:49 INFO - Intel Iris: 10:01:49 INFO - Chipset Model: Intel Iris 10:01:49 INFO - Type: GPU 10:01:49 INFO - Bus: Built-In 10:01:49 INFO - VRAM (Dynamic, Max): 1536 MB 10:01:49 INFO - Vendor: Intel (0x8086) 10:01:49 INFO - Device ID: 0x0a2e 10:01:49 INFO - Revision ID: 0x0009 10:01:49 INFO - Displays: 10:01:49 INFO - Display: 10:01:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:01:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:01:49 INFO - Display Serial Number: 1600x1200 60 10:01:49 INFO - Main Display: Yes 10:01:49 INFO - Mirror: Off 10:01:49 INFO - Online: Yes 10:01:49 INFO - Rotation: Supported 10:01:49 INFO - Return code: 0 10:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:01:49 INFO - Python 2.7.3 10:01:49 INFO - Return code: 0 10:01:49 INFO - grabbing minidump binary from tooltool 10:01: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')]} 10:01:49 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 0x106e04d30>, '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 0x106b69db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dfcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:01:49 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 10:01:49 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 10:01:49 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 10:01:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:01:49 INFO - Return code: 0 10:01:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:01:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:01:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:01:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:01:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--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 10:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0289 --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 10:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ye42LVof2X/Render', 10:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:49 INFO - 'HOME': '/Users/cltbld', 10:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:49 INFO - 'LOGNAME': 'cltbld', 10:01:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:01:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:49 INFO - 'MOZ_NO_REMOTE': '1', 10:01:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:01:49 INFO - 'NO_EM_RESTART': '1', 10:01:49 INFO - 'PAGER': '/bin/cat', 10:01:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:49 INFO - 'PWD': '/builds/slave/test', 10:01:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:49 INFO - 'SHELL': '/bin/bash', 10:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kl4aeK3DFq/Listeners', 10:01:49 INFO - 'TMPDIR': '/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/', 10:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:49 INFO - 'USER': 'cltbld', 10:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:49 INFO - 'XPC_FLAGS': '0x0', 10:01:49 INFO - 'XPC_SERVICE_NAME': '0', 10:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0289', '--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 10:01:49 INFO - mozversion application_buildid: 20160704073634 10:01:49 INFO - mozversion application_changeset: 93cc9bcee8c88643cec1961884fc5750edfd57d3 10:01:49 INFO - mozversion application_display_name: Nightly 10:01:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:01:49 INFO - mozversion application_name: Firefox 10:01:49 INFO - mozversion application_remotingname: firefox 10:01:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:01:49 INFO - mozversion application_vendor: Mozilla 10:01:49 INFO - mozversion application_version: 50.0a1 10:01:49 INFO - mozversion platform_buildid: 20160704073634 10:01:49 INFO - mozversion platform_changeset: 93cc9bcee8c88643cec1961884fc5750edfd57d3 10:01:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:01:49 INFO - mozversion platform_version: 50.0a1 10:01:49 INFO - using testdate: 1467651709 10:01:49 INFO - actual date: 1467651709 10:01:49 INFO - starting webserver on 'localhost:49268' 10:01:49 INFO - SUITE-START | Running 2 tests 10:01:49 INFO - TEST-START | dromaeo_css 10:01:49 INFO - operating with platform_type : mac_ 10:01:49 INFO - Initialising browser for dromaeo_css test... 10:01:49 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpJKC14t/profile http://localhost:49268/getInfo.html) 10:01:51 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 10:01:51 INFO - PROCESS | 1660 | colorDepth:24 10:01:51 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 10:01:51 INFO - PROCESS | 1660 | __metrics 10:01:51 INFO - PROCESS | 1660 | 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" 10:01:51 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:01:52 INFO - TEST-INFO | 1660: exit 0 10:01:52 INFO - Browser initialized. 10:01:52 INFO - Running cycle 1/1 for dromaeo_css test... 10:01:52 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpJKC14t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:02:04 INFO - PROCESS | 1663 | RSS: Main: 244592640 10:02:04 INFO - PROCESS | 1663 | 10:02:56 INFO - PROCESS | 1663 | 1467651776132 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] 10:05:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 10:05:07 INFO - PROCESS | 1663 | RSS: Main: 367136768 10:05:07 INFO - PROCESS | 1663 | 10:08:10 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 10:08:11 INFO - PROCESS | 1663 | RSS: Main: 377815040 10:08:11 INFO - PROCESS | 1663 | 10:11:13 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 10:11:14 INFO - PROCESS | 1663 | RSS: Main: 369881088 10:11:14 INFO - PROCESS | 1663 | 10:14:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 10:14:17 INFO - PROCESS | 1663 | RSS: Main: 368041984 10:14:17 INFO - PROCESS | 1663 | 10:17:20 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 10:17:21 INFO - PROCESS | 1663 | RSS: Main: 330539008 10:17:21 INFO - PROCESS | 1663 | 10:20:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 10:20:25 INFO - PROCESS | 1663 | RSS: Main: 386719744 10:20:25 INFO - PROCESS | 1663 | 10:20:25 INFO - PROCESS | 1663 | __start_tp_report 10:20:25 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 10:20:25 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 10:20:25 INFO - PROCESS | 1663 | |i|pagename|runs| 10:20:25 INFO - PROCESS | 1663 | |0;dojo.html;3372.27496088341;3383.2049468374944;3384.1201287665217;3387.356402283566;3448.8792892248775;5629.35262444819;5658.688772117532;5738.3974682658345;5769.249997500325;5832.445917637829;5708;5722.141678748189;5841.299044114712;5853.414658534144;5854.795082172125;5142.717150556718;5177.456367081459;5234.685918844882;5237.3715154181555;5359.437259087798;5364.7317634118335;5380.91928621071;5390.51485366317;5400.513953744162;5405.621606487547;5211.504907033845;5243.475652434726;5271.341082364705;5272.261883336336;5323.680579165262;5207.505286997767;5211.140161873286;5227.555657769094;5292.682439053669;5296.126139187029;5171.250168725552;5179.2749015137915;5232.947670523322;5270.973645131749;5335.6798592084215;5671.092625179951;5792.073268277098;5885.411458854081;5890.057590785454;5907.261592300963;6192.535559833031;6237.6257424554215;6266.060090986343;6294.779682711129;6315.147455093595;5993;6197.87604247917;6257.812265632038;6278.121063051177;6302.338254483259;5700.144978253295;5769.624974376652;5837.795677151279;5853.063509838448;5892.499137573354;6168.814935551941;6209.0996805462955;6230.314665386803;6268.905966410507;6279.277883043508;6195.938040619626;6202.038684003986;6208.4722798561725;6213.409726076015;6229.345918678564;6151.3541078187045;6199.876002480015;6231.065340199007;6289.276733175652;6323.9051414228825;5263.6841789492755;6223.906641400383;6275.215598050244;6293.402126797947;6299.937000629935;6254.593451425642;6279.120923070774;6288.654124023223;6302.401271879165;6335.9862422013175;4859.416869975626;4917.803287868445;4958.181899986534;4967.180415231446;4994.1509943309;4399.846005389796;4404.383386325917;4427.446569178853;4454.821807127743;4456.688031837805;5781.103928891029;5788.942110578841;5807.970960145172;5829.09649004405;5877.676727779927;5222.764975576032;6126.731429653803;6163.506919446433;6208.099825525273;6220.688965551704;5805.245318108618;5851.268591426072;5855.853603659943;5860.912086318709;5872.29532456108;5386.730663466811;5417.674939503563;5418.864528386822;5424.349078110652;5430.91853622196;6154.569180157346;6210.782622608187;6232.626042437469;6297.716602752887;6309.716062777186;6264.21697287839;6266.843328916813;6269.185005949197;6269.843253918506;6282.560220784501;6284.717187726655;6312.7159277833525;6315.305316415099;6318.873622527615;6325.588836725598;6075.908861367171;6120.508083830972;6173.290071641639;6176.3514830943905;6205.944989351911;6229.626222426669;6251.593646412968;6271.027990661455;6292.874142517216;6307.053941908567;6161.137440758387;6161.353057929032;6166.722497487534;6174.475169610633;6186.1339412483185;5931.110333450017;5996.760129594767;6065.302490213682;6072.787452439144;6143.96928015357;6005.129256257731;6006.819795406145;6010.7295171716505;6028.457438830526;6050.15297858309;6097;6158.107074474266;6165.815025549241;6167.506599471942;6172.259328880563;5655.179998900218;5871.882562348815;5939.970300148644;5940.138679891478;5949.8215053548465 10:20:25 INFO - PROCESS | 1663 | |1;ext.html;509.23868816119904;510.6374474123372;516.4628786062495;537.1459379586457;553.689933637163;30005;41746;42664.573354266446;42830.357544636856;45229;43590.78204608981;44138.779306103424;44139.77930110353;44160.558394416046;44314;9014.54927253638;9810.803783924299;9999.600015999351;10000.499975001221;10008.399496030224;8895.911040889572;9137.360384773068;9138.268938484905;9138.360314777969;11985.28088314704;11935.16453848231;12222.816657750142;12237.632871013839;12247.816282755766;12290.139690221687;12102.636920892388;12255.69360765979;12268.447919843538;12272.26366418009;12295;8536.231739143506;8731.956340218321;8738.344624153151;12100.576479823252;12194.512219511209;32804.70367074306;40920.59079409198;41058.38412423816;41159.176816463805;43513.78243108796;3523.2072783623735;3578.141246100917;3983.64147226751;4291.742495450283;4296.441462609872;31529.842350788098;32551.837240813882;32953.835230823934;32959.83520082408;33187.3362532748;3233.5958005249345;3375.8312084395684;3400.081977865987;3416.26550291686;3451.4822776583464;543628;544410.6676799703;546046;546202;546914;59503.80992380128;59645.701771490865;60102.39897600968;60163.09755353673;60220.69889650524;62306;62811.371886281464;62937;62963.685181574714;62982.370176298566;16446.6710665786;16750.4137355192;16752.329906803836;16756.748648770277;16808.411705590246;12892.419841107172;12964.416601252966;12971.675708107372;12985.091043627044;12991.545295914597;12437.253764774143;14131.576052718436;14487.92756036213;15167.544973650809;15668.764968525482;5604.299462567179;6042.425969532887;6578.3092775258865;6584.11114499546;6668.833279167962;895.359817730326;982.94102353859;1076.3004047369116;1077.3697387028676;1133.869604995436;27328.04351847676;27667.861660691568;28726.85636571804;30164.54753178704;30253.941112060973;1049.732318258839;1102.459794700591;1106.2035334559105;1147.1339138950038;1216.7323188898429;15556.45552405683;15735.842641573667;15849.920750396173;15905.522834314988;15936.123513206652;656122;671234;676004;676657;677678;51220.74389628103;51543.74228128835;59455;59565;59614.403855961755;1377.2218696436682;1439.2084353605476;1453.280626090071;1460.8100946876839;1466.8093147890706;30427.54358684577;30966.05248297174;31569.36861262716;31709.36581268408;32600.347993039533;45624.08751825011;45849.54150458586;46120.53879461296;46324.53675463202;46363;408572;473382;475437;475515;476715;34126.82936585301;39720.602793972845;40182.1963560733;41062.1787564253;41093.97265068397;48535;49104.263436047775;49698;50208.24687629615;50214.744631383044;36518.8174059128;36602.45096323504;40739.79630101831;41420.75737727873;41514.37728434015;38429.6157038426;39964.60035399609;40481.797591011855;41326.553570624885;41763.58236417597;38535.8439246823;38766.64316749013;39323.410148848365;39651.20697585974;39682.80158599189;34117.48823767695;34323.485147722306;35434.93695189148;35471.64528354684;35564.822175888956;25752.4548527089;25797;25876.706164691313;26432.339191520365;26503.33741656474 10:20:25 INFO - PROCESS | 1663 | |2;jquery.html;333.5397151930336;353.9769914955529;375.8778397020969;379.9221159662268;399.4288167919875;15542.067475951451;16759.329626814928;20000.59998800023;20159.899200504013;20679.862607556577;21115.57768844622;21356.57286854266;21741.021654025568;21765.3470395888;22052.558948821053;5578.776848926038;5924.674142922137;6139.079138129272;6143.078538219258;6890.379865812076;6763.492738044642;6765.594064356154;6819.931800682004;6828.214755303129;6833.214180369247;6615.603063816162;6703.765368212114;6708.731650733965;6716.5634233774645;6785.558938668995;5422.674639521641;5740.799072032501;6562.179727534059;6659.533832631718;6681.732730690766;5638.802641907555;5728.598998070137;5755.65466072037;5759.136129580555;5764.740586673568;26210.737892621022;26448.338791530172;26760;26769.79535920888;26995.730042699517;2220.222921977295;2228.687983682286;2273.9317820465412;2590.300618832923;2596.2600658812335;15633.452829150925;16156.111413872231;16305.347786088541;16324.102174380405;16336.918315408348;1523.2840564934463;1801.369520667769;1843.4699199664246;1845.750823638805;1906;126590.36704816417;128026.08786288915;128145;128276;129753;8565.95717021411;11326;11466.31202127875;11652.650420487398;11830.800065976626;14492.637684057983;14561;14597.051191672508;14599.562013139623;14604.488842890449;7312.378447831888;8615.61229744663;8812.515311657791;8815.338849586307;8954.86567701485;8845.027047024822;8863.512506812187;8879.467231966102;9021.278297736166;9023.77440563978;4516.412866327357;4591.8392856249875;4602.631789456836;4655.976720116378;4669.159551280734;1907.637548865711;1915.7260421819612;1931.65230258552;1980.9009549522755;1985.6227316810048;865.6277800545826;955.9044095590524;963.7735132243918;1001.8647482589903;1055.9260851740305;20574.58850822975;20772.272970446265;20847;21001.264955726478;21157.126423102847;918.7427520294328;923.1875949164827;932.3706498113746;962.1965658675085;970.1705042188827;10786.298890572089;11371;11382.317060976367;11391.715207119889;11404.714882128013;578904;593984;594674;613465;613780;90005.09994900099;124823;126586.36706816407;133136;136834;928.4475736936512;947.9336446448821;962.5572236771023;965.3966271080575;965.700632803819;19444.708329375353;19455.8054419454;19496.707549386487;19578.412647620593;19586.824790512615;44416.333754994346;45882.31176532288;49568.01727930842;54322.72838635781;58870.41129588649;82702.17297826945;83490.16509835067;83499.5825020871;83756;84181;6763.628000460022;7104.3961263293195;8002.799930001796;8092.87860682102;8306.501609903426;12002;13609.387577558835;13722.931385343009;13789.310534473438;13948.4420623178;10838.783224335626;11645.88354116448;11908.464119114487;11928.0457563393;11939.283642981449;11607.883921161017;11668.766624667289;11677.474513646737;11807.763844722886;11881;11740.236884602473;11769.470373833026;11774.52901883915;11922.403879806148;11936.985356244817;6485.319041500763;7422.851542969219;7576.924230757621;7671.61641917913;7720.845583088195;11542.480588373712;11730.648080557597;11732.882671173178;11922.821157682469;11982.10134239918 10:20:25 INFO - PROCESS | 1663 | |3;mootools.html;1540.6071451779799;1610.5731981025026;1719.4927496388573;1848.0759620189906;1885.5387075016852;7240.674169662365;7931.762047138595;8124.878126828087;9052.45685258885;9110.407823491469;7302;7938.603069846514;8017.238362355582;8285.171482851712;8552.5296108714;2302.7236731592234;2329.871857047862;2368.7631236876346;2368.9407764805933;2373.7270213925362;2314.1784666443423;2320.5474932388192;2362.4212068043266;2367.9763202368013;2374.1097088591778;2109.4515425989275;2179.9564008719817;2309.284121922201;2373.537160253751;2374.9287521374386;2334.323046316566;2336.042222688698;2357.198552492144;2358.7877091061887;2373.905043798246;2099.6640537513927;2311.2257393773107;2362.2913126062112;2371.1345358944036;2374.631932050535;8601;8787.209151176361;8794.296456283546;8803.955980220122;8815.823683526358;5262.2632831403635;5388.568914486871;5395.487428694268;5397.433269506724;5424.05079111154;3067.5552044953356;3586.22896077342;3658.4695219193068;3663.7618554793853;3691.4093745000673;1063.957441702331;1068.8824229334766;1086.2613422872444;1107.4075369677264;1108.695108845066;9595.28035397348;11569.652910412786;11582.305061696325;11583.247088939192;11595.536178552848;4557.567031132037;4586.357909892551;4759.833405830779;4780.880477988078;4796.496367881322;10090.545925433373;10092.848607270897;10093;10097.697069087939;10098.394096354243;5323.893522129536;5325.760340784674;5331.573474122061;5331.573474122061;5343.412224655285;4754.286856971482;4777.522247775264;4846.951530484721;4856.223004319362;4935.90128197434;3805.60041195676;3811.19964807388;3855.36386496225;3885.941710874339;3913.941290880639;1112.9610463633896;1115.8716747574133;1162.4710756605723;1164.126904821384;1174.30129073203;739.3272122368619;875.6847534887436;912.2610685344891;913.8355096082769;941.2987324443334;5804.796832110856;5949.8397812426165;5954.434328738763;6023.247094113236;6067.0292753160165;999.9050090241416;1028.7428142964259;1041.3387498938077;1079.1205167788385;1140.9657710268705;3367.8316084195694;3377.7804442711144;3776.848061341317;3842.634949679776;4079.102597428561;10541.84187237192;10758.031777140095;10776.084032857296;10876.347419154717;10881.075108615698;9732.513374331254;10287.485625718684;10803.891961080446;10813.513391897226;10853.51159197838;7941.808728690743;8284.295834854105;8301.045379781282;8305.37709671765;8318.66725330992;5449.5640348772795;5996.4603185713495;6101.5118790497545;6145.0782382642965;6208.00671892495;9654.827663775593;9953.353032053183;9957.701268961942;9998;10031.147352475122;9493.857592135986;10117.08946194846;10121.746956326151;10220.4378759166;10221.386716797017;7470.3276705096805;7574.204708505527;7595.848083038198;7597.810054748676;7662.118856331594;8277.379196560403;8322.583870806315;8357.707480238163;8365.790855228668;8381.371397145116;5978.432048955342;6538.280789113099;6653.068570400244;7458.73894413693;7685.11621163551;7134.32223938725;7394.112706475257;7398.297161769623;7455.888161677688;7460.813479663052;6954.165500140016;7041.3310735480045;7047.823804404931;7049;7050.189228238613;4926.334944782552;5023.995200959896;5059.06407314648;5064.442911279682;5140.56305214046;5001.949980500294;5058.418281897481;5075.720835353944;5099;5221.608379371638 10:20:25 INFO - PROCESS | 1663 | |4;prototype.html;298.04179562705895;303.3008914452187;311.6027065491499;314.55804594544645;314.8347117763174;4775.068861571995;4964.8014079436825;5206.349206349207;5208.588374614624;5219.556337711289;4864.297495876442;4866.66400127938;5039.294498770166;5122.667026643266;5156;3801.3727734923705;3959.8218080186316;3961.0295477607956;3980.6019398060253;4061.1877624475073;3370.5449764281875;4028.0463974972263;4036.9596304037022;4049.7367671101433;4072.490938632671;2962.7852580442022;3336.332733453307;3719.2003719200366;3804.8668296609485;3994.820233089518;3968.1666849961543;3991.501062367204;3992.920141597152;4023.002862867168;4024.8993775155564;3044.3606842562867;3132.4494375284153;3368.528406023159;3483.407820670492;3503.1767534629344;4832.903341933142;5025.949740502585;5041.369828771403;5069.417017043013;5077.517635824591;4542.545745425464;4542.591166794971;4589.311603259504;4603.654725895572;4611.1930412177735;4714.717116972993;5372.7313634318125;5393.649412788156;5447.4824891635235;5479.287692599976;3261.673832616743;3398.915027124317;3657.7622454540638;3706.3328600851837;3849.5188101487315;5150.896982060375;5625.831225063254;5656.624217828522;5657.981563318601;5684.630499017592;3342.5988881334397;3346.3474622448875;3873.8837834865;3876.089119057019;3877.7091718121255;3971.602839716063;3971.860984865516;3997.720159588801;3997.760134391946;4010.7794071326357;4293.355996600535;4536.342230376643;4540.841070562515;4562.634989200856;4588.839390621379;4302.376155457441;4566.374334757527;5313.282706834605;5405.540529054996;5432.103702889058;1329.1360615599938;1352.0062753875893;1355.9932200339133;1359.2727890578492;1360.2042804959012;785.2618538573722;822.268181318627;847.9781862855244;924.306769922558;928.8018456191572;4136.482939632546;4465.642748580106;4570.948681803204;4972.602191824645;5037.848864534069;4222.366645003212;4669.439667239885;5428;5457.20870473787;5564.109742441271;3853.5183102112237;4775.498572649891;4865.343178670834;5026.346574945233;5035.471275516092;2878.8848446061925;2893.1609833148573;2895.956560651592;2912.723291287324;2926.7365937065765;5215.148622239127;5659.405762394889;5664.923664503731;5677.829665110054;5697.829065128053;4955.479674634112;4987.977464619744;5310.761015754223;5575.498205161555;5585.748641311151;5172.094883395391;5194.688318700814;5299.205119232146;5585.245991791056;5696.601237913306;4321.546237645128;4739.597134243628;5455.290812194389;5472.097103977799;5476.780928762805;4992.425871024879;5393.328068298683;5409.188621706776;5427.4301198373605;5457.7271136443815;4833.347498087714;5634.549236061023;5668.404817494104;5671.461211184931;5711.914321285101;3748.812559372075;3857.826397812049;3875.224955009066;3878.5151856017997;3900.902477437973;4606.56237657422;5405.621606487665;5440.809571664972;5449.128139497661;5458.536024437967;5280.313559237275;5286.444923283;5348.1442969124755;5355.919661205163;5380.757865896122;4337.566243375637;5008.697738587921;5139.904783585626;5181.766820493162;5378.354597448331;4253.553376895381;4741.004389078332;4839.790052486878;5046.369203849519;5224.738763061908;4560.6351491879905;4756.215224488059;5127.3847138343635;5375.56995440356;5441.183822426668;4591.357209990537;4852.320675105559;5192.24712416705;5211.5049070338255;5215.478452154754 10:20:25 INFO - PROCESS | 1663 | |5;yui.html;710.8969199466078;716.6345163966378;735.8896165575163;761.3908872901677;859.9828003439927;14341.569752907404;16769;16788.748168777463;16792.58018549534;16828.49514514565;14247.71504569908;15021.774673379909;15049.92475037626;15197.316120774563;15366.69266614667;893.0444424465808;894.4856707393243;894.7539426657688;896.1397058823504;897.33148804141;885.0795173020084;895.5790778334205;895.8566629339307;895.8880139982502;897.1477096758073;938.9154976052154;939.7697564096806;940.463935556733;940.666063547441;942.9434233945985;932.5943214701626;933.108881018629;934.2152591822834;935.1396715022196;937.446906325268;688.5111570784749;689.8447849233933;690.0649619765246;692.5290802254465;692.9861402771917;13550.186988780704;13601.659958501017;13602.183868967893;13609.727805443934;13625.863741362558;12135.757284854253;12180.878191218151;12311.44598493061;12444.81332780009;12589.93705031469;9819.705408837746;9861.556229969668;9864.26018048642;9881.802363952753;10250.9487452563;3798.1833905710405;3877.0501227199234;3911.0613452771413;4029.4157347184787;4040.4343391925154;76967;111346.32980505298;120352;120578.39710801477;120718;11420.657380278604;11714.941425292818;11860.88139118615;12144.534959268365;12190.451429685685;21214.893925530272;21658.241961531272;21663.78336216649;21669.133234670753;21672.56654866894;6815.318468153145;7643.808904777314;8423.957880210683;8441.366897482714;8446.873296900552;4343.326784348431;4546.318052292183;4561.657875659339;4582.5417458253905;4716.481187069488;3517.5929628148947;3668.3396988541786;3669.9266014679556;3672.6143754905884;3676.5955744868043;657.2901266632122;671.9832004199934;679.6465837764334;695.3950063444836;695.3984803145344;651.680676468527;663.6548994522917;696.7178292791427;697.6930150733763;711.2496316386158;8962.462252264755;9318.953405233065;9383;9429.811403772022;9630.614775409045;508.8524327944855;563.2368141168657;636.9108324834527;678.9083473731082;682.436989483678;4737.147313483606;4740.170470167775;4740.952590474051;4743.31221972812;4750.572448479654;131730.34134829266;132461;133969;138712;154549;112782;115399;115742.42128789303;117210;118062.409687951;71385.6430717843;104467.47766261273;105559;106384;106438;9952.502374881082;10018.64934727281;10025.276728029254;10039;10088.830901356243;57132;57154.7142264286;57330.42669573251;60457.093143603764;60974.695126524086;56867;56981;76716.23283767315;89290.55354723184;91053.54473227591;13300.068995170632;13325.33373331349;13422.865771342553;13771;14254.85961123087;13070.738585228431;14878.628034299229;14923.925380373028;15413.76879346833;15441.305141268445;13897.374618142007;14339.713205735618;14354.85645143577;14641.341139648528;14675.339609717375;14060.450697508015;14224.644383890485;14257.71484570282;14484.34820433062;14704.77942830837;13581.796273055714;13610.455581776618;13619;13763.174209547458;14389.992700510864;11337.659870203906;11403.657890263305;11474.368909710047;11522.420227095834;11531.942340288246;10994.395308258123;11150.72123196894;11282.097432205239;11706;11718.648440546796 10:20:25 INFO - PROCESS | 1663 | __end_tp_report 10:20:25 INFO - PROCESS | 1663 | __start_cc_report 10:20:25 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1276 10:20:25 INFO - PROCESS | 1663 | __end_cc_report 10:20:25 INFO - PROCESS | 1663 | __startTimestamp1467652825055__endTimestamp 10:20:25 INFO - PROCESS | 1663 | 10:20:25 INFO - PROCESS | 1663 | ------- Summary: start ------- 10:20:25 INFO - PROCESS | 1663 | Number of tests: 6 10:20:25 INFO - PROCESS | 1663 | 10:20:25 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5768.89 Median:5949.82 stddev:629.54 (10.6%) stddev-sans-first:602.75 10:20:25 INFO - PROCESS | 1663 | Values: 3372.3 3383.2 3384.1 3387.4 3448.9 5629.4 5658.7 5738.4 5769.2 5832.4 5708.0 5722.1 5841.3 5853.4 5854.8 5142.7 5177.5 5234.7 5237.4 5359.4 5364.7 5380.9 5390.5 5400.5 5405.6 5211.5 5243.5 5271.3 5272.3 5323.7 5207.5 5211.1 5227.6 5292.7 5296.1 5171.3 5179.3 5232.9 5271.0 5335.7 5671.1 5792.1 5885.4 5890.1 5907.3 6192.5 6237.6 6266.1 6294.8 6315.1 5993.0 6197.9 6257.8 6278.1 6302.3 5700.1 5769.6 5837.8 5853.1 5892.5 6168.8 6209.1 6230.3 6268.9 6279.3 6195.9 6202.0 6208.5 6213.4 6229.3 6151.4 6199.9 6231.1 6289.3 6323.9 5263.7 6223.9 6275.2 6293.4 6299.9 6254.6 6279.1 6288.7 6302.4 6336.0 4859.4 4917.8 4958.2 4967.2 4994.2 4399.8 4404.4 4427.4 4454.8 4456.7 5781.1 5788.9 5808.0 5829.1 5877.7 5222.8 6126.7 6163.5 6208.1 6220.7 5805.2 5851.3 5855.9 5860.9 5872.3 5386.7 5417.7 5418.9 5424.3 5430.9 6154.6 6210.8 6232.6 6297.7 6309.7 6264.2 6266.8 6269.2 6269.8 6282.6 6284.7 6312.7 6315.3 6318.9 6325.6 6075.9 6120.5 6173.3 6176.4 6205.9 6229.6 6251.6 6271.0 6292.9 6307.1 6161.1 6161.4 6166.7 6174.5 6186.1 5931.1 5996.8 6065.3 6072.8 6144.0 6005.1 6006.8 6010.7 6028.5 6050.2 6097.0 6158.1 6165.8 6167.5 6172.3 5655.2 5871.9 5940.0 5940.1 5949.8 10:20:25 INFO - PROCESS | 1663 | 10:20:25 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:70044.69 Median:39701.70 stddev:151264.58 (381.0%) stddev-sans-first:151598.82 10:20:25 INFO - PROCESS | 1663 | Values: 509.2 510.6 516.5 537.1 553.7 30005.0 41746.0 42664.6 42830.4 45229.0 43590.8 44138.8 44139.8 44160.6 44314.0 9014.5 9810.8 9999.6 10000.5 10008.4 8895.9 9137.4 9138.3 9138.4 11985.3 11935.2 12222.8 12237.6 12247.8 12290.1 12102.6 12255.7 12268.4 12272.3 12295.0 8536.2 8732.0 8738.3 12100.6 12194.5 32804.7 40920.6 41058.4 41159.2 43513.8 3523.2 3578.1 3983.6 4291.7 4296.4 31529.8 32551.8 32953.8 32959.8 33187.3 3233.6 3375.8 3400.1 3416.3 3451.5 543628.0 544410.7 546046.0 546202.0 546914.0 59503.8 59645.7 60102.4 60163.1 60220.7 62306.0 62811.4 62937.0 62963.7 62982.4 16446.7 16750.4 16752.3 16756.7 16808.4 12892.4 12964.4 12971.7 12985.1 12991.5 12437.3 14131.6 14487.9 15167.5 15668.8 5604.3 6042.4 6578.3 6584.1 6668.8 895.4 982.9 1076.3 1077.4 1133.9 27328.0 27667.9 28726.9 30164.5 30253.9 1049.7 1102.5 1106.2 1147.1 1216.7 15556.5 15735.8 15849.9 15905.5 15936.1 656122.0 671234.0 676004.0 676657.0 677678.0 51220.7 51543.7 59455.0 59565.0 59614.4 1377.2 1439.2 1453.3 1460.8 1466.8 30427.5 30966.1 31569.4 31709.4 32600.3 45624.1 45849.5 46120.5 46324.5 46363.0 408572.0 473382.0 475437.0 475515.0 476715.0 34126.8 39720.6 40182.2 41062.2 41094.0 48535.0 49104.3 49698.0 50208.2 50214.7 36518.8 36602.5 40739.8 41420.8 41514.4 38429.6 39964.6 40481.8 41326.6 41763.6 38535.8 38766.6 39323.4 39651.2 39682.8 34117.5 34323.5 35434.9 35471.6 35564.8 25752.5 25797.0 25876.7 26432.3 26503.3 10:20:25 INFO - PROCESS | 1663 | 10:20:25 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:35615.18 Median:26329.54 stddev:100110.55 (380.2%) stddev-sans-first:100356.33 10:20:25 INFO - PROCESS | 1663 | Values: 333.5 354.0 375.9 379.9 399.4 15542.1 16759.3 20000.6 20159.9 20679.9 21115.6 21356.6 21741.0 21765.3 22052.6 5578.8 5924.7 6139.1 6143.1 6890.4 6763.5 6765.6 6819.9 6828.2 6833.2 6615.6 6703.8 6708.7 6716.6 6785.6 5422.7 5740.8 6562.2 6659.5 6681.7 5638.8 5728.6 5755.7 5759.1 5764.7 26210.7 26448.3 26760.0 26769.8 26995.7 2220.2 2228.7 2273.9 2590.3 2596.3 15633.5 16156.1 16305.3 16324.1 16336.9 1523.3 1801.4 1843.5 1845.8 1906.0 126590.4 128026.1 128145.0 128276.0 129753.0 8566.0 11326.0 11466.3 11652.7 11830.8 14492.6 14561.0 14597.1 14599.6 14604.5 7312.4 8615.6 8812.5 8815.3 8954.9 8845.0 8863.5 8879.5 9021.3 9023.8 4516.4 4591.8 4602.6 4656.0 4669.2 1907.6 1915.7 1931.7 1980.9 1985.6 865.6 955.9 963.8 1001.9 1055.9 20574.6 20772.3 20847.0 21001.3 21157.1 918.7 923.2 932.4 962.2 970.2 10786.3 11371.0 11382.3 11391.7 11404.7 578904.0 593984.0 594674.0 613465.0 613780.0 90005.1 124823.0 126586.4 133136.0 136834.0 928.4 947.9 962.6 965.4 965.7 19444.7 19455.8 19496.7 19578.4 19586.8 44416.3 45882.3 49568.0 54322.7 58870.4 82702.2 83490.2 83499.6 83756.0 84181.0 6763.6 7104.4 8002.8 8092.9 8306.5 12002.0 13609.4 13722.9 13789.3 13948.4 10838.8 11645.9 11908.5 11928.0 11939.3 11607.9 11668.8 11677.5 11807.8 11881.0 11740.2 11769.5 11774.5 11922.4 11937.0 6485.3 7422.9 7576.9 7671.6 7720.8 11542.5 11730.6 11732.9 11922.8 11982.1 10:20:25 INFO - PROCESS | 1663 | 10:20:25 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5663.27 Median:4891.28 stddev:3153.41 (64.5%) stddev-sans-first:3147.04 10:20:25 INFO - PROCESS | 1663 | Values: 1540.6 1610.6 1719.5 1848.1 1885.5 7240.7 7931.8 8124.9 9052.5 9110.4 7302.0 7938.6 8017.2 8285.2 8552.5 2302.7 2329.9 2368.8 2368.9 2373.7 2314.2 2320.5 2362.4 2368.0 2374.1 2109.5 2180.0 2309.3 2373.5 2374.9 2334.3 2336.0 2357.2 2358.8 2373.9 2099.7 2311.2 2362.3 2371.1 2374.6 8601.0 8787.2 8794.3 8804.0 8815.8 5262.3 5388.6 5395.5 5397.4 5424.1 3067.6 3586.2 3658.5 3663.8 3691.4 1064.0 1068.9 1086.3 1107.4 1108.7 9595.3 11569.7 11582.3 11583.2 11595.5 4557.6 4586.4 4759.8 4780.9 4796.5 10090.5 10092.8 10093.0 10097.7 10098.4 5323.9 5325.8 5331.6 5331.6 5343.4 4754.3 4777.5 4847.0 4856.2 4935.9 3805.6 3811.2 3855.4 3885.9 3913.9 1113.0 1115.9 1162.5 1164.1 1174.3 739.3 875.7 912.3 913.8 941.3 5804.8 5949.8 5954.4 6023.2 6067.0 999.9 1028.7 1041.3 1079.1 1141.0 3367.8 3377.8 3776.8 3842.6 4079.1 10541.8 10758.0 10776.1 10876.3 10881.1 9732.5 10287.5 10803.9 10813.5 10853.5 7941.8 8284.3 8301.0 8305.4 8318.7 5449.6 5996.5 6101.5 6145.1 6208.0 9654.8 9953.4 9957.7 9998.0 10031.1 9493.9 10117.1 10121.7 10220.4 10221.4 7470.3 7574.2 7595.8 7597.8 7662.1 8277.4 8322.6 8357.7 8365.8 8381.4 5978.4 6538.3 6653.1 7458.7 7685.1 7134.3 7394.1 7398.3 7455.9 7460.8 6954.2 7041.3 7047.8 7049.0 7050.2 4926.3 5024.0 5059.1 5064.4 5140.6 5001.9 5058.4 5075.7 5099.0 5221.6 10:20:25 INFO - PROCESS | 1663 | 10:20:25 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4343.26 Median:4833.13 stddev:1298.08 (26.9%) stddev-sans-first:1265.71 10:20:25 INFO - PROCESS | 1663 | Values: 298.0 303.3 311.6 314.6 314.8 4775.1 4964.8 5206.3 5208.6 5219.6 4864.3 4866.7 5039.3 5122.7 5156.0 3801.4 3959.8 3961.0 3980.6 4061.2 3370.5 4028.0 4037.0 4049.7 4072.5 2962.8 3336.3 3719.2 3804.9 3994.8 3968.2 3991.5 3992.9 4023.0 4024.9 3044.4 3132.4 3368.5 3483.4 3503.2 4832.9 5025.9 5041.4 5069.4 5077.5 4542.5 4542.6 4589.3 4603.7 4611.2 4714.7 5372.7 5393.6 5447.5 5479.3 3261.7 3398.9 3657.8 3706.3 3849.5 5150.9 5625.8 5656.6 5658.0 5684.6 3342.6 3346.3 3873.9 3876.1 3877.7 3971.6 3971.9 3997.7 3997.8 4010.8 4293.4 4536.3 4540.8 4562.6 4588.8 4302.4 4566.4 5313.3 5405.5 5432.1 1329.1 1352.0 1356.0 1359.3 1360.2 785.3 822.3 848.0 924.3 928.8 4136.5 4465.6 4570.9 4972.6 5037.8 4222.4 4669.4 5428.0 5457.2 5564.1 3853.5 4775.5 4865.3 5026.3 5035.5 2878.9 2893.2 2896.0 2912.7 2926.7 5215.1 5659.4 5664.9 5677.8 5697.8 4955.5 4988.0 5310.8 5575.5 5585.7 5172.1 5194.7 5299.2 5585.2 5696.6 4321.5 4739.6 5455.3 5472.1 5476.8 4992.4 5393.3 5409.2 5427.4 5457.7 4833.3 5634.5 5668.4 5671.5 5711.9 3748.8 3857.8 3875.2 3878.5 3900.9 4606.6 5405.6 5440.8 5449.1 5458.5 5280.3 5286.4 5348.1 5355.9 5380.8 4337.6 5008.7 5139.9 5181.8 5378.4 4253.6 4741.0 4839.8 5046.4 5224.7 4560.6 4756.2 5127.4 5375.6 5441.2 4591.4 4852.3 5192.2 5211.5 5215.5 10:20:25 INFO - PROCESS | 1663 | 10:20:25 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:23370.22 Median:3671.27 stddev:36460.76 (993.1%) stddev-sans-first:36523.34 10:20:25 INFO - PROCESS | 1663 | Values: 710.9 716.6 735.9 761.4 860.0 14341.6 16769.0 16788.7 16792.6 16828.5 14247.7 15021.8 15049.9 15197.3 15366.7 893.0 894.5 894.8 896.1 897.3 885.1 895.6 895.9 895.9 897.1 938.9 939.8 940.5 940.7 942.9 932.6 933.1 934.2 935.1 937.4 688.5 689.8 690.1 692.5 693.0 13550.2 13601.7 13602.2 13609.7 13625.9 12135.8 12180.9 12311.4 12444.8 12589.9 9819.7 9861.6 9864.3 9881.8 10250.9 3798.2 3877.1 3911.1 4029.4 4040.4 76967.0 111346.3 120352.0 120578.4 120718.0 11420.7 11714.9 11860.9 12144.5 12190.5 21214.9 21658.2 21663.8 21669.1 21672.6 6815.3 7643.8 8424.0 8441.4 8446.9 4343.3 4546.3 4561.7 4582.5 4716.5 3517.6 3668.3 3669.9 3672.6 3676.6 657.3 672.0 679.6 695.4 695.4 651.7 663.7 696.7 697.7 711.2 8962.5 9319.0 9383.0 9429.8 9630.6 508.9 563.2 636.9 678.9 682.4 4737.1 4740.2 4741.0 4743.3 4750.6 131730.3 132461.0 133969.0 138712.0 154549.0 112782.0 115399.0 115742.4 117210.0 118062.4 71385.6 104467.5 105559.0 106384.0 106438.0 9952.5 10018.6 10025.3 10039.0 10088.8 57132.0 57154.7 57330.4 60457.1 60974.7 56867.0 56981.0 76716.2 89290.6 91053.5 13300.1 13325.3 13422.9 13771.0 14254.9 13070.7 14878.6 14923.9 15413.8 15441.3 13897.4 14339.7 14354.9 14641.3 14675.3 14060.5 14224.6 14257.7 14484.3 14704.8 13581.8 13610.5 13619.0 13763.2 14390.0 11337.7 11403.7 11474.4 11522.4 11531.9 10994.4 11150.7 11282.1 11706.0 11718.6 10:20:25 INFO - PROCESS | 1663 | -------- Summary: end -------- 10:20:25 INFO - PROCESS | 1663 | 10:20:25 INFO - TEST-INFO | 1663: exit 0 10:20:25 INFO - TEST-OK | dromaeo_css | took 1116134ms 10:20:25 INFO - TEST-START | kraken 10:20:25 INFO - operating with platform_type : mac_ 10:20:25 INFO - Initialising browser for kraken test... 10:20:25 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpH2uATo/profile http://localhost:49268/getInfo.html) 10:20:28 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 10:20:28 INFO - PROCESS | 1670 | colorDepth:24 10:20:28 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 10:20:28 INFO - PROCESS | 1670 | __metrics 10:20:28 INFO - PROCESS | 1670 | 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" 10:20:28 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:20:28 INFO - TEST-INFO | 1670: exit 0 10:20:28 INFO - Browser initialized. 10:20:28 INFO - Running cycle 1/1 for kraken test... 10:20:28 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/T/tmpH2uATo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:20:40 INFO - PROCESS | 1671 | RSS: Main: 244563968 10:20:40 INFO - PROCESS | 1671 | 10:20:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 10:20:44 INFO - PROCESS | 1671 | RSS: Main: 294150144 10:20:44 INFO - PROCESS | 1671 | 10:20:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 10:20:47 INFO - PROCESS | 1671 | RSS: Main: 304640000 10:20:47 INFO - PROCESS | 1671 | 10:20:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 10:20:51 INFO - PROCESS | 1671 | RSS: Main: 388546560 10:20:51 INFO - PROCESS | 1671 | 10:20:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 10:20:54 INFO - PROCESS | 1671 | RSS: Main: 291553280 10:20:54 INFO - PROCESS | 1671 | 10:20:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:20:57 INFO - PROCESS | 1671 | RSS: Main: 296005632 10:20:57 INFO - PROCESS | 1671 | 10:21:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 10:21:00 INFO - PROCESS | 1671 | RSS: Main: 334245888 10:21:00 INFO - PROCESS | 1671 | 10:21:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 10:21:04 INFO - PROCESS | 1671 | RSS: Main: 334565376 10:21:04 INFO - PROCESS | 1671 | 10:21:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 10:21:08 INFO - PROCESS | 1671 | RSS: Main: 334503936 10:21:08 INFO - PROCESS | 1671 | 10:21:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:21:10 INFO - PROCESS | 1671 | RSS: Main: 311558144 10:21:10 INFO - PROCESS | 1671 | 10:21:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:21:12 INFO - PROCESS | 1671 | RSS: Main: 318091264 10:21:12 INFO - PROCESS | 1671 | 10:21:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:21:15 INFO - PROCESS | 1671 | RSS: Main: 315846656 10:21:15 INFO - PROCESS | 1671 | 10:21:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:21:19 INFO - PROCESS | 1671 | RSS: Main: 325959680 10:21:19 INFO - PROCESS | 1671 | 10:21:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:21:23 INFO - PROCESS | 1671 | RSS: Main: 328310784 10:21:23 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 10:21:24 INFO - PROCESS | 1671 | RSS: Main: 371572736 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | __start_tp_report 10:21:24 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 10:21:24 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 10:21:24 INFO - PROCESS | 1671 | |i|pagename|runs| 10:21:24 INFO - PROCESS | 1671 | |0;ai-astar;101;112;114;113;113;111;112;113;112;113 10:21:24 INFO - PROCESS | 1671 | |1;audio-beat-detection;140;105;106;156;107;107;128;110;136;125 10:21:24 INFO - PROCESS | 1671 | |2;audio-dft;183;183;182;198;230;263;204;180;182;183 10:21:24 INFO - PROCESS | 1671 | |3;audio-fft;78;74;48;105;73;48;76;77;108;80 10:21:24 INFO - PROCESS | 1671 | |4;audio-oscillator;87;84;92;93;98;96;103;69;67;98 10:21:24 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;89;86;89;87;89;86;89;87;89;86 10:21:24 INFO - PROCESS | 1671 | |6;imaging-darkroom;101;148;96;96;95;95;114;149;118;95 10:21:24 INFO - PROCESS | 1671 | |7;imaging-desaturate;84;86;84;84;83;84;84;98;131;140 10:21:24 INFO - PROCESS | 1671 | |8;json-parse-financial;55;54;53;54;55;54;53;73;82;43 10:21:24 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;73;74;75;74;72;69;72;70;70 10:21:24 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;83;86;83;84;86;82;84;83;83;82 10:21:24 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;132;137;138;142;146;145;150;151;250;155 10:21:24 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;173;220;217;167;173;168;165;162;161;163 10:21:24 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;66;66;67;67;67;65;66;66;65;66 10:21:24 INFO - PROCESS | 1671 | __end_tp_report 10:21:24 INFO - PROCESS | 1671 | __start_cc_report 10:21:24 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2047 10:21:24 INFO - PROCESS | 1671 | __end_cc_report 10:21:24 INFO - PROCESS | 1671 | __startTimestamp1467652884986__endTimestamp 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | ------- Summary: start ------- 10:21:24 INFO - PROCESS | 1671 | Number of tests: 14 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.40 Median:113.00 stddev:3.75 (3.3%) stddev-sans-first:0.88 10:21:24 INFO - PROCESS | 1671 | Values: 101.0 112.0 114.0 113.0 113.0 111.0 112.0 113.0 112.0 113.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:126.50 stddev:17.83 (14.1%) stddev-sans-first:17.68 10:21:24 INFO - PROCESS | 1671 | Values: 140.0 105.0 106.0 156.0 107.0 107.0 128.0 110.0 136.0 125.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:198.80 Median:190.50 stddev:27.47 (14.4%) stddev-sans-first:28.53 10:21:24 INFO - PROCESS | 1671 | Values: 183.0 183.0 182.0 198.0 230.0 263.0 204.0 180.0 182.0 183.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:76.70 Median:75.00 stddev:19.61 (26.2%) stddev-sans-first:20.80 10:21:24 INFO - PROCESS | 1671 | Values: 78.0 74.0 48.0 105.0 73.0 48.0 76.0 77.0 108.0 80.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:92.50 stddev:12.22 (13.2%) stddev-sans-first:12.95 10:21:24 INFO - PROCESS | 1671 | Values: 87.0 84.0 92.0 93.0 98.0 96.0 103.0 69.0 67.0 98.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:89.00 stddev:1.42 (1.6%) stddev-sans-first:1.42 10:21:24 INFO - PROCESS | 1671 | Values: 89.0 86.0 89.0 87.0 89.0 86.0 89.0 87.0 89.0 86.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:110.70 Median:95.00 stddev:21.57 (22.7%) stddev-sans-first:22.59 10:21:24 INFO - PROCESS | 1671 | Values: 101.0 148.0 96.0 96.0 95.0 95.0 114.0 149.0 118.0 95.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:84.00 stddev:21.48 (25.6%) stddev-sans-first:22.36 10:21:24 INFO - PROCESS | 1671 | Values: 84.0 86.0 84.0 84.0 83.0 84.0 84.0 98.0 131.0 140.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:54.50 stddev:11.26 (20.7%) stddev-sans-first:11.90 10:21:24 INFO - PROCESS | 1671 | Values: 55.0 54.0 53.0 54.0 55.0 54.0 53.0 73.0 82.0 43.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.50 stddev:2.99 (4.1%) stddev-sans-first:2.09 10:21:24 INFO - PROCESS | 1671 | Values: 65.0 73.0 74.0 75.0 74.0 72.0 69.0 72.0 70.0 70.0 10:21:24 INFO - PROCESS | 1671 | 10:21:24 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:83.50 stddev:1.43 (1.7%) stddev-sans-first:1.50 10:21:25 INFO - PROCESS | 1671 | Values: 83.0 86.0 83.0 84.0 86.0 82.0 84.0 83.0 83.0 82.0 10:21:25 INFO - PROCESS | 1671 | 10:21:25 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:148.00 stddev:34.25 (23.1%) stddev-sans-first:35.34 10:21:25 INFO - PROCESS | 1671 | Values: 132.0 137.0 138.0 142.0 146.0 145.0 150.0 151.0 250.0 155.0 10:21:25 INFO - PROCESS | 1671 | 10:21:25 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:170.50 stddev:22.32 (13.1%) stddev-sans-first:23.63 10:21:25 INFO - PROCESS | 1671 | Values: 173.0 220.0 217.0 167.0 173.0 168.0 165.0 162.0 161.0 163.0 10:21:25 INFO - PROCESS | 1671 | 10:21:25 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 10:21:25 INFO - PROCESS | 1671 | Values: 66.0 66.0 67.0 67.0 67.0 65.0 66.0 66.0 65.0 66.0 10:21:25 INFO - PROCESS | 1671 | -------- Summary: end -------- 10:21:25 INFO - PROCESS | 1671 | 10:21:25 INFO - TEST-INFO | 1671: exit 0 10:21:25 INFO - TEST-OK | kraken | took 60101ms 10:21:25 INFO - SUITE-END | took 1176s 10:21:26 INFO - Completed test suite (00:19:36) 10:21:26 INFO - javascript benchmark 10:21:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3372.27496088341, 3383.2049468374944, 3384.1201287665217, 3387.356402283566, 3448.8792892248775, 5629.35262444819, 5658.688772117532, 5738.3974682658345, 5769.249997500325, 5832.445917637829, 5708.0, 5722.141678748189, 5841.299044114712, 5853.414658534144, 5854.795082172125, 5142.717150556718, 5177.456367081459, 5234.685918844882, 5237.3715154181555, 5359.437259087798, 5364.7317634118335, 5380.91928621071, 5390.51485366317, 5400.513953744162, 5405.621606487547, 5211.504907033845, 5243.475652434726, 5271.341082364705, 5272.261883336336, 5323.680579165262, 5207.505286997767, 5211.140161873286, 5227.555657769094, 5292.682439053669, 5296.126139187029, 5171.250168725552, 5179.2749015137915, 5232.947670523322, 5270.973645131749, 5335.6798592084215, 5671.092625179951, 5792.073268277098, 5885.411458854081, 5890.057590785454, 5907.261592300963, 6192.535559833031, 6237.6257424554215, 6266.060090986343, 6294.779682711129, 6315.147455093595, 5993.0, 6197.87604247917, 6257.812265632038, 6278.121063051177, 6302.338254483259, 5700.144978253295, 5769.624974376652, 5837.795677151279, 5853.063509838448, 5892.499137573354, 6168.814935551941, 6209.0996805462955, 6230.314665386803, 6268.905966410507, 6279.277883043508, 6195.938040619626, 6202.038684003986, 6208.4722798561725, 6213.409726076015, 6229.345918678564, 6151.3541078187045, 6199.876002480015, 6231.065340199007, 6289.276733175652, 6323.9051414228825, 5263.6841789492755, 6223.906641400383, 6275.215598050244, 6293.402126797947, 6299.937000629935, 6254.593451425642, 6279.120923070774, 6288.654124023223, 6302.401271879165, 6335.9862422013175, 4859.416869975626, 4917.803287868445, 4958.181899986534, 4967.180415231446, 4994.1509943309, 4399.846005389796, 4404.383386325917, 4427.446569178853, 4454.821807127743, 4456.688031837805, 5781.103928891029, 5788.942110578841, 5807.970960145172, 5829.09649004405, 5877.676727779927, 5222.764975576032, 6126.731429653803, 6163.506919446433, 6208.099825525273, 6220.688965551704, 5805.245318108618, 5851.268591426072, 5855.853603659943, 5860.912086318709, 5872.29532456108, 5386.730663466811, 5417.674939503563, 5418.864528386822, 5424.349078110652, 5430.91853622196, 6154.569180157346, 6210.782622608187, 6232.626042437469, 6297.716602752887, 6309.716062777186, 6264.21697287839, 6266.843328916813, 6269.185005949197, 6269.843253918506, 6282.560220784501, 6284.717187726655, 6312.7159277833525, 6315.305316415099, 6318.873622527615, 6325.588836725598, 6075.908861367171, 6120.508083830972, 6173.290071641639, 6176.3514830943905, 6205.944989351911, 6229.626222426669, 6251.593646412968, 6271.027990661455, 6292.874142517216, 6307.053941908567, 6161.137440758387, 6161.353057929032, 6166.722497487534, 6174.475169610633, 6186.1339412483185, 5931.110333450017, 5996.760129594767, 6065.302490213682, 6072.787452439144, 6143.96928015357, 6005.129256257731, 6006.819795406145, 6010.7295171716505, 6028.457438830526, 6050.15297858309, 6097.0, 6158.107074474266, 6165.815025549241, 6167.506599471942, 6172.259328880563, 5655.179998900218, 5871.882562348815, 5939.970300148644, 5940.138679891478, 5949.8215053548465], "value": 5729.09047688082, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.23868816119904, 510.6374474123372, 516.4628786062495, 537.1459379586457, 553.689933637163, 30005.0, 41746.0, 42664.573354266446, 42830.357544636856, 45229.0, 43590.78204608981, 44138.779306103424, 44139.77930110353, 44160.558394416046, 44314.0, 9014.54927253638, 9810.803783924299, 9999.600015999351, 10000.499975001221, 10008.399496030224, 8895.911040889572, 9137.360384773068, 9138.268938484905, 9138.360314777969, 11985.28088314704, 11935.16453848231, 12222.816657750142, 12237.632871013839, 12247.816282755766, 12290.139690221687, 12102.636920892388, 12255.69360765979, 12268.447919843538, 12272.26366418009, 12295.0, 8536.231739143506, 8731.956340218321, 8738.344624153151, 12100.576479823252, 12194.512219511209, 32804.70367074306, 40920.59079409198, 41058.38412423816, 41159.176816463805, 43513.78243108796, 3523.2072783623735, 3578.141246100917, 3983.64147226751, 4291.742495450283, 4296.441462609872, 31529.842350788098, 32551.837240813882, 32953.835230823934, 32959.83520082408, 33187.3362532748, 3233.5958005249345, 3375.8312084395684, 3400.081977865987, 3416.26550291686, 3451.4822776583464, 543628.0, 544410.6676799703, 546046.0, 546202.0, 546914.0, 59503.80992380128, 59645.701771490865, 60102.39897600968, 60163.09755353673, 60220.69889650524, 62306.0, 62811.371886281464, 62937.0, 62963.685181574714, 62982.370176298566, 16446.6710665786, 16750.4137355192, 16752.329906803836, 16756.748648770277, 16808.411705590246, 12892.419841107172, 12964.416601252966, 12971.675708107372, 12985.091043627044, 12991.545295914597, 12437.253764774143, 14131.576052718436, 14487.92756036213, 15167.544973650809, 15668.764968525482, 5604.299462567179, 6042.425969532887, 6578.3092775258865, 6584.11114499546, 6668.833279167962, 895.359817730326, 982.94102353859, 1076.3004047369116, 1077.3697387028676, 1133.869604995436, 27328.04351847676, 27667.861660691568, 28726.85636571804, 30164.54753178704, 30253.941112060973, 1049.732318258839, 1102.459794700591, 1106.2035334559105, 1147.1339138950038, 1216.7323188898429, 15556.45552405683, 15735.842641573667, 15849.920750396173, 15905.522834314988, 15936.123513206652, 656122.0, 671234.0, 676004.0, 676657.0, 677678.0, 51220.74389628103, 51543.74228128835, 59455.0, 59565.0, 59614.403855961755, 1377.2218696436682, 1439.2084353605476, 1453.280626090071, 1460.8100946876839, 1466.8093147890706, 30427.54358684577, 30966.05248297174, 31569.36861262716, 31709.36581268408, 32600.347993039533, 45624.08751825011, 45849.54150458586, 46120.53879461296, 46324.53675463202, 46363.0, 408572.0, 473382.0, 475437.0, 475515.0, 476715.0, 34126.82936585301, 39720.602793972845, 40182.1963560733, 41062.1787564253, 41093.97265068397, 48535.0, 49104.263436047775, 49698.0, 50208.24687629615, 50214.744631383044, 36518.8174059128, 36602.45096323504, 40739.79630101831, 41420.75737727873, 41514.37728434015, 38429.6157038426, 39964.60035399609, 40481.797591011855, 41326.553570624885, 41763.58236417597, 38535.8439246823, 38766.64316749013, 39323.410148848365, 39651.20697585974, 39682.80158599189, 34117.48823767695, 34323.485147722306, 35434.93695189148, 35471.64528354684, 35564.822175888956, 25752.4548527089, 25797.0, 25876.706164691313, 26432.339191520365, 26503.33741656474], "value": 20594.85083584602, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.5397151930336, 353.9769914955529, 375.8778397020969, 379.9221159662268, 399.4288167919875, 15542.067475951451, 16759.329626814928, 20000.59998800023, 20159.899200504013, 20679.862607556577, 21115.57768844622, 21356.57286854266, 21741.021654025568, 21765.3470395888, 22052.558948821053, 5578.776848926038, 5924.674142922137, 6139.079138129272, 6143.078538219258, 6890.379865812076, 6763.492738044642, 6765.594064356154, 6819.931800682004, 6828.214755303129, 6833.214180369247, 6615.603063816162, 6703.765368212114, 6708.731650733965, 6716.5634233774645, 6785.558938668995, 5422.674639521641, 5740.799072032501, 6562.179727534059, 6659.533832631718, 6681.732730690766, 5638.802641907555, 5728.598998070137, 5755.65466072037, 5759.136129580555, 5764.740586673568, 26210.737892621022, 26448.338791530172, 26760.0, 26769.79535920888, 26995.730042699517, 2220.222921977295, 2228.687983682286, 2273.9317820465412, 2590.300618832923, 2596.2600658812335, 15633.452829150925, 16156.111413872231, 16305.347786088541, 16324.102174380405, 16336.918315408348, 1523.2840564934463, 1801.369520667769, 1843.4699199664246, 1845.750823638805, 1906.0, 126590.36704816417, 128026.08786288915, 128145.0, 128276.0, 129753.0, 8565.95717021411, 11326.0, 11466.31202127875, 11652.650420487398, 11830.800065976626, 14492.637684057983, 14561.0, 14597.051191672508, 14599.562013139623, 14604.488842890449, 7312.378447831888, 8615.61229744663, 8812.515311657791, 8815.338849586307, 8954.86567701485, 8845.027047024822, 8863.512506812187, 8879.467231966102, 9021.278297736166, 9023.77440563978, 4516.412866327357, 4591.8392856249875, 4602.631789456836, 4655.976720116378, 4669.159551280734, 1907.637548865711, 1915.7260421819612, 1931.65230258552, 1980.9009549522755, 1985.6227316810048, 865.6277800545826, 955.9044095590524, 963.7735132243918, 1001.8647482589903, 1055.9260851740305, 20574.58850822975, 20772.272970446265, 20847.0, 21001.264955726478, 21157.126423102847, 918.7427520294328, 923.1875949164827, 932.3706498113746, 962.1965658675085, 970.1705042188827, 10786.298890572089, 11371.0, 11382.317060976367, 11391.715207119889, 11404.714882128013, 578904.0, 593984.0, 594674.0, 613465.0, 613780.0, 90005.09994900099, 124823.0, 126586.36706816407, 133136.0, 136834.0, 928.4475736936512, 947.9336446448821, 962.5572236771023, 965.3966271080575, 965.700632803819, 19444.708329375353, 19455.8054419454, 19496.707549386487, 19578.412647620593, 19586.824790512615, 44416.333754994346, 45882.31176532288, 49568.01727930842, 54322.72838635781, 58870.41129588649, 82702.17297826945, 83490.16509835067, 83499.5825020871, 83756.0, 84181.0, 6763.628000460022, 7104.3961263293195, 8002.799930001796, 8092.87860682102, 8306.501609903426, 12002.0, 13609.387577558835, 13722.931385343009, 13789.310534473438, 13948.4420623178, 10838.783224335626, 11645.88354116448, 11908.464119114487, 11928.0457563393, 11939.283642981449, 11607.883921161017, 11668.766624667289, 11677.474513646737, 11807.763844722886, 11881.0, 11740.236884602473, 11769.470373833026, 11774.52901883915, 11922.403879806148, 11936.985356244817, 6485.319041500763, 7422.851542969219, 7576.924230757621, 7671.61641917913, 7720.845583088195, 11542.480588373712, 11730.648080557597, 11732.882671173178, 11922.821157682469, 11982.10134239918], "value": 9891.292145319758, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.6071451779799, 1610.5731981025026, 1719.4927496388573, 1848.0759620189906, 1885.5387075016852, 7240.674169662365, 7931.762047138595, 8124.878126828087, 9052.45685258885, 9110.407823491469, 7302.0, 7938.603069846514, 8017.238362355582, 8285.171482851712, 8552.5296108714, 2302.7236731592234, 2329.871857047862, 2368.7631236876346, 2368.9407764805933, 2373.7270213925362, 2314.1784666443423, 2320.5474932388192, 2362.4212068043266, 2367.9763202368013, 2374.1097088591778, 2109.4515425989275, 2179.9564008719817, 2309.284121922201, 2373.537160253751, 2374.9287521374386, 2334.323046316566, 2336.042222688698, 2357.198552492144, 2358.7877091061887, 2373.905043798246, 2099.6640537513927, 2311.2257393773107, 2362.2913126062112, 2371.1345358944036, 2374.631932050535, 8601.0, 8787.209151176361, 8794.296456283546, 8803.955980220122, 8815.823683526358, 5262.2632831403635, 5388.568914486871, 5395.487428694268, 5397.433269506724, 5424.05079111154, 3067.5552044953356, 3586.22896077342, 3658.4695219193068, 3663.7618554793853, 3691.4093745000673, 1063.957441702331, 1068.8824229334766, 1086.2613422872444, 1107.4075369677264, 1108.695108845066, 9595.28035397348, 11569.652910412786, 11582.305061696325, 11583.247088939192, 11595.536178552848, 4557.567031132037, 4586.357909892551, 4759.833405830779, 4780.880477988078, 4796.496367881322, 10090.545925433373, 10092.848607270897, 10093.0, 10097.697069087939, 10098.394096354243, 5323.893522129536, 5325.760340784674, 5331.573474122061, 5331.573474122061, 5343.412224655285, 4754.286856971482, 4777.522247775264, 4846.951530484721, 4856.223004319362, 4935.90128197434, 3805.60041195676, 3811.19964807388, 3855.36386496225, 3885.941710874339, 3913.941290880639, 1112.9610463633896, 1115.8716747574133, 1162.4710756605723, 1164.126904821384, 1174.30129073203, 739.3272122368619, 875.6847534887436, 912.2610685344891, 913.8355096082769, 941.2987324443334, 5804.796832110856, 5949.8397812426165, 5954.434328738763, 6023.247094113236, 6067.0292753160165, 999.9050090241416, 1028.7428142964259, 1041.3387498938077, 1079.1205167788385, 1140.9657710268705, 3367.8316084195694, 3377.7804442711144, 3776.848061341317, 3842.634949679776, 4079.102597428561, 10541.84187237192, 10758.031777140095, 10776.084032857296, 10876.347419154717, 10881.075108615698, 9732.513374331254, 10287.485625718684, 10803.891961080446, 10813.513391897226, 10853.51159197838, 7941.808728690743, 8284.295834854105, 8301.045379781282, 8305.37709671765, 8318.66725330992, 5449.5640348772795, 5996.4603185713495, 6101.5118790497545, 6145.0782382642965, 6208.00671892495, 9654.827663775593, 9953.353032053183, 9957.701268961942, 9998.0, 10031.147352475122, 9493.857592135986, 10117.08946194846, 10121.746956326151, 10220.4378759166, 10221.386716797017, 7470.3276705096805, 7574.204708505527, 7595.848083038198, 7597.810054748676, 7662.118856331594, 8277.379196560403, 8322.583870806315, 8357.707480238163, 8365.790855228668, 8381.371397145116, 5978.432048955342, 6538.280789113099, 6653.068570400244, 7458.73894413693, 7685.11621163551, 7134.32223938725, 7394.112706475257, 7398.297161769623, 7455.888161677688, 7460.813479663052, 6954.165500140016, 7041.3310735480045, 7047.823804404931, 7049.0, 7050.189228238613, 4926.334944782552, 5023.995200959896, 5059.06407314648, 5064.442911279682, 5140.56305214046, 5001.949980500294, 5058.418281897481, 5075.720835353944, 5099.0, 5221.608379371638], "value": 4565.87610204396, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.04179562705895, 303.3008914452187, 311.6027065491499, 314.55804594544645, 314.8347117763174, 4775.068861571995, 4964.8014079436825, 5206.349206349207, 5208.588374614624, 5219.556337711289, 4864.297495876442, 4866.66400127938, 5039.294498770166, 5122.667026643266, 5156.0, 3801.3727734923705, 3959.8218080186316, 3961.0295477607956, 3980.6019398060253, 4061.1877624475073, 3370.5449764281875, 4028.0463974972263, 4036.9596304037022, 4049.7367671101433, 4072.490938632671, 2962.7852580442022, 3336.332733453307, 3719.2003719200366, 3804.8668296609485, 3994.820233089518, 3968.1666849961543, 3991.501062367204, 3992.920141597152, 4023.002862867168, 4024.8993775155564, 3044.3606842562867, 3132.4494375284153, 3368.528406023159, 3483.407820670492, 3503.1767534629344, 4832.903341933142, 5025.949740502585, 5041.369828771403, 5069.417017043013, 5077.517635824591, 4542.545745425464, 4542.591166794971, 4589.311603259504, 4603.654725895572, 4611.1930412177735, 4714.717116972993, 5372.7313634318125, 5393.649412788156, 5447.4824891635235, 5479.287692599976, 3261.673832616743, 3398.915027124317, 3657.7622454540638, 3706.3328600851837, 3849.5188101487315, 5150.896982060375, 5625.831225063254, 5656.624217828522, 5657.981563318601, 5684.630499017592, 3342.5988881334397, 3346.3474622448875, 3873.8837834865, 3876.089119057019, 3877.7091718121255, 3971.602839716063, 3971.860984865516, 3997.720159588801, 3997.760134391946, 4010.7794071326357, 4293.355996600535, 4536.342230376643, 4540.841070562515, 4562.634989200856, 4588.839390621379, 4302.376155457441, 4566.374334757527, 5313.282706834605, 5405.540529054996, 5432.103702889058, 1329.1360615599938, 1352.0062753875893, 1355.9932200339133, 1359.2727890578492, 1360.2042804959012, 785.2618538573722, 822.268181318627, 847.9781862855244, 924.306769922558, 928.8018456191572, 4136.482939632546, 4465.642748580106, 4570.948681803204, 4972.602191824645, 5037.848864534069, 4222.366645003212, 4669.439667239885, 5428.0, 5457.20870473787, 5564.109742441271, 3853.5183102112237, 4775.498572649891, 4865.343178670834, 5026.346574945233, 5035.471275516092, 2878.8848446061925, 2893.1609833148573, 2895.956560651592, 2912.723291287324, 2926.7365937065765, 5215.148622239127, 5659.405762394889, 5664.923664503731, 5677.829665110054, 5697.829065128053, 4955.479674634112, 4987.977464619744, 5310.761015754223, 5575.498205161555, 5585.748641311151, 5172.094883395391, 5194.688318700814, 5299.205119232146, 5585.245991791056, 5696.601237913306, 4321.546237645128, 4739.597134243628, 5455.290812194389, 5472.097103977799, 5476.780928762805, 4992.425871024879, 5393.328068298683, 5409.188621706776, 5427.4301198373605, 5457.7271136443815, 4833.347498087714, 5634.549236061023, 5668.404817494104, 5671.461211184931, 5711.914321285101, 3748.812559372075, 3857.826397812049, 3875.224955009066, 3878.5151856017997, 3900.902477437973, 4606.56237657422, 5405.621606487665, 5440.809571664972, 5449.128139497661, 5458.536024437967, 5280.313559237275, 5286.444923283, 5348.1442969124755, 5355.919661205163, 5380.757865896122, 4337.566243375637, 5008.697738587921, 5139.904783585626, 5181.766820493162, 5378.354597448331, 4253.553376895381, 4741.004389078332, 4839.790052486878, 5046.369203849519, 5224.738763061908, 4560.6351491879905, 4756.215224488059, 5127.3847138343635, 5375.56995440356, 5441.183822426668, 4591.357209990537, 4852.320675105559, 5192.24712416705, 5211.5049070338255, 5215.478452154754], "value": 3937.3765725707876, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.8969199466078, 716.6345163966378, 735.8896165575163, 761.3908872901677, 859.9828003439927, 14341.569752907404, 16769.0, 16788.748168777463, 16792.58018549534, 16828.49514514565, 14247.71504569908, 15021.774673379909, 15049.92475037626, 15197.316120774563, 15366.69266614667, 893.0444424465808, 894.4856707393243, 894.7539426657688, 896.1397058823504, 897.33148804141, 885.0795173020084, 895.5790778334205, 895.8566629339307, 895.8880139982502, 897.1477096758073, 938.9154976052154, 939.7697564096806, 940.463935556733, 940.666063547441, 942.9434233945985, 932.5943214701626, 933.108881018629, 934.2152591822834, 935.1396715022196, 937.446906325268, 688.5111570784749, 689.8447849233933, 690.0649619765246, 692.5290802254465, 692.9861402771917, 13550.186988780704, 13601.659958501017, 13602.183868967893, 13609.727805443934, 13625.863741362558, 12135.757284854253, 12180.878191218151, 12311.44598493061, 12444.81332780009, 12589.93705031469, 9819.705408837746, 9861.556229969668, 9864.26018048642, 9881.802363952753, 10250.9487452563, 3798.1833905710405, 3877.0501227199234, 3911.0613452771413, 4029.4157347184787, 4040.4343391925154, 76967.0, 111346.32980505298, 120352.0, 120578.39710801477, 120718.0, 11420.657380278604, 11714.941425292818, 11860.88139118615, 12144.534959268365, 12190.451429685685, 21214.893925530272, 21658.241961531272, 21663.78336216649, 21669.133234670753, 21672.56654866894, 6815.318468153145, 7643.808904777314, 8423.957880210683, 8441.366897482714, 8446.873296900552, 4343.326784348431, 4546.318052292183, 4561.657875659339, 4582.5417458253905, 4716.481187069488, 3517.5929628148947, 3668.3396988541786, 3669.9266014679556, 3672.6143754905884, 3676.5955744868043, 657.2901266632122, 671.9832004199934, 679.6465837764334, 695.3950063444836, 695.3984803145344, 651.680676468527, 663.6548994522917, 696.7178292791427, 697.6930150733763, 711.2496316386158, 8962.462252264755, 9318.953405233065, 9383.0, 9429.811403772022, 9630.614775409045, 508.8524327944855, 563.2368141168657, 636.9108324834527, 678.9083473731082, 682.436989483678, 4737.147313483606, 4740.170470167775, 4740.952590474051, 4743.31221972812, 4750.572448479654, 131730.34134829266, 132461.0, 133969.0, 138712.0, 154549.0, 112782.0, 115399.0, 115742.42128789303, 117210.0, 118062.409687951, 71385.6430717843, 104467.47766261273, 105559.0, 106384.0, 106438.0, 9952.502374881082, 10018.64934727281, 10025.276728029254, 10039.0, 10088.830901356243, 57132.0, 57154.7142264286, 57330.42669573251, 60457.093143603764, 60974.695126524086, 56867.0, 56981.0, 76716.23283767315, 89290.55354723184, 91053.54473227591, 13300.068995170632, 13325.33373331349, 13422.865771342553, 13771.0, 14254.85961123087, 13070.738585228431, 14878.628034299229, 14923.925380373028, 15413.76879346833, 15441.305141268445, 13897.374618142007, 14339.713205735618, 14354.85645143577, 14641.341139648528, 14675.339609717375, 14060.450697508015, 14224.644383890485, 14257.71484570282, 14484.34820433062, 14704.77942830837, 13581.796273055714, 13610.455581776618, 13619.0, 13763.174209547458, 14389.992700510864, 11337.659870203906, 11403.657890263305, 11474.368909710047, 11522.420227095834, 11531.942340288246, 10994.395308258123, 11150.72123196894, 11282.097432205239, 11706.0, 11718.648440546796], "value": 7820.348361905539, "unit": "score"}], "name": "dromaeo_css", "value": 7399.187237426252, "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, 111.0, 112.0, 113.0, 112.0, 113.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 105.0, 106.0, 156.0, 107.0, 107.0, 128.0, 110.0, 136.0, 125.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 182.0, 198.0, 230.0, 263.0, 204.0, 180.0, 182.0, 183.0], "value": 198.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 48.0, 105.0, 73.0, 48.0, 76.0, 77.0, 108.0, 80.0], "value": 76.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 92.0, 93.0, 98.0, 96.0, 103.0, 69.0, 67.0, 98.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 87.0, 89.0, 86.0, 89.0, 87.0, 89.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 148.0, 96.0, 96.0, 95.0, 95.0, 114.0, 149.0, 118.0, 95.0], "value": 110.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 84.0, 83.0, 84.0, 84.0, 98.0, 131.0, 140.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 54.0, 55.0, 54.0, 53.0, 73.0, 82.0, 43.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 74.0, 75.0, 74.0, 72.0, 69.0, 72.0, 70.0, 70.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 83.0, 84.0, 86.0, 82.0, 84.0, 83.0, 83.0, 82.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 137.0, 138.0, 142.0, 146.0, 145.0, 150.0, 151.0, 250.0, 155.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 220.0, 217.0, 167.0, 173.0, 168.0, 165.0, 162.0, 161.0, 163.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 67.0, 65.0, 66.0, 66.0, 65.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1502.0, "alertThreshold": 2.0}]} 10:21:26 INFO - Return code: 0 10:21:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:21:26 INFO - # TBPL SUCCESS # 10:21:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:21:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:21:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:21:26 INFO - 2016-07-04 10:21:26.319 screenresolution[1675:10562] starting screenresolution argv=screenresolution get 10:21:26 INFO - 2016-07-04 10:21:26.322 screenresolution[1675:10562] Display 0: 1600x1200x32@60 10:21:26 INFO - 2016-07-04 10:21:26.328 screenresolution[1676:10565] starting screenresolution argv=screenresolution list 10:21:26 INFO - Available Modes on Display 0 10:21:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:21:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:21:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:21:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:21:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:21:26 INFO - Intel Iris: 10:21:26 INFO - Chipset Model: Intel Iris 10:21:26 INFO - Type: GPU 10:21:26 INFO - Bus: Built-In 10:21:26 INFO - VRAM (Dynamic, Max): 1536 MB 10:21:26 INFO - Vendor: Intel (0x8086) 10:21:26 INFO - Device ID: 0x0a2e 10:21:26 INFO - Revision ID: 0x0009 10:21:26 INFO - Displays: 10:21:26 INFO - Display: 10:21:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:21:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:21:26 INFO - Display Serial Number: 1600x1200 60 10:21:26 INFO - Main Display: Yes 10:21:26 INFO - Mirror: Off 10:21:26 INFO - Online: Yes 10:21:26 INFO - Rotation: Supported 10:21:26 INFO - Return code: 0 10:21:26 INFO - Running post-action listener: _resource_record_post_action 10:21:26 INFO - ##### 10:21:26 INFO - ##### Finished run-tests step (success) 10:21:26 INFO - ##### 10:21:26 INFO - Running post-run listener: _resource_record_post_run 10:21:26 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 31686144; Write bytes: 706979328; Read time: 195; Write time: 1975 10:21:26 INFO - TinderboxPrint: CPU usage
53.3% 10:21:26 INFO - TinderboxPrint: I/O read bytes / time
31,686,144 / 195 10:21:26 INFO - TinderboxPrint: I/O write bytes / time
706,979,328 / 1,975 10:21:26 INFO - TinderboxPrint: CPU idle
2,262.7 (46.7%) 10:21:26 INFO - TinderboxPrint: CPU system
964.5 (19.9%) 10:21:26 INFO - TinderboxPrint: CPU user
1,622.5 (33.5%) 10:21:26 INFO - TinderboxPrint: Swap in / out
435,343,360 / 0 10:21:26 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 408007168; Write bytes: 410885632; Read time: 30812; Write time: 881 10:21:26 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29261312; Write bytes: 294537216; Read time: 174; Write time: 1089 10:21:27 INFO - Running post-run listener: _upload_blobber_files 10:21:27 INFO - Blob upload gear active. 10:21:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:21:27 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 10:21:27 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:21:27 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:21:27 INFO - (blobuploader) - INFO - Open directory for files ... 10:21:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:21:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:21:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:28 INFO - (blobuploader) - INFO - Done attempting. 10:21:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:21:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:21:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:29 INFO - (blobuploader) - INFO - Done attempting. 10:21:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:21:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:21:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:21:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:21:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:21:31 INFO - (blobuploader) - INFO - Done attempting. 10:21:31 INFO - (blobuploader) - INFO - Iteration through files over. 10:21:31 INFO - Return code: 0 10:21:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:21:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:21:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1a6b2c2092488f221b0421a6a24b798e27d109ed1458823aa0009202760adc7ae3f03acb420db02c41ba9b4c84da8a8286f88f7b48027a350873b384174b9cd2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b04875b089e2cb5210b15126ffb9a7771a12052e4e5c7626c0bc87f3b38706aed700b99f05edef9d9a50fb2aa39f78c904a4e6208b3cdb51a98ec2bb807b172b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c71079979486698c305991f76789b212d16c3b38e3365f441460b1eb8291f1d1ae7ceb4295a180c081e2e99b2523b145739d151e3ea8f0c4039c20ab3cf25142"} 10:21:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:21:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:21:31 INFO - Contents: 10:21:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1a6b2c2092488f221b0421a6a24b798e27d109ed1458823aa0009202760adc7ae3f03acb420db02c41ba9b4c84da8a8286f88f7b48027a350873b384174b9cd2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b04875b089e2cb5210b15126ffb9a7771a12052e4e5c7626c0bc87f3b38706aed700b99f05edef9d9a50fb2aa39f78c904a4e6208b3cdb51a98ec2bb807b172b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c71079979486698c305991f76789b212d16c3b38e3365f441460b1eb8291f1d1ae7ceb4295a180c081e2e99b2523b145739d151e3ea8f0c4039c20ab3cf25142"} 10:21:31 INFO - Running post-run listener: copy_logs_to_upload_dir 10:21:31 INFO - Copying logs to upload dir... 10:21:31 INFO - mkdir: /builds/slave/test/build/upload/logs 10:21:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.059010 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-07-04 10:21:31.844333) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 10:21:31.850110) ========= 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.Ye42LVof2X/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.kl4aeK3DFq/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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/Autoland/sha512/1a6b2c2092488f221b0421a6a24b798e27d109ed1458823aa0009202760adc7ae3f03acb420db02c41ba9b4c84da8a8286f88f7b48027a350873b384174b9cd2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b04875b089e2cb5210b15126ffb9a7771a12052e4e5c7626c0bc87f3b38706aed700b99f05edef9d9a50fb2aa39f78c904a4e6208b3cdb51a98ec2bb807b172b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c71079979486698c305991f76789b212d16c3b38e3365f441460b1eb8291f1d1ae7ceb4295a180c081e2e99b2523b145739d151e3ea8f0c4039c20ab3cf25142"} build_url:https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011053 build_url: 'https://queue.taskcluster.net/v1/task/PYxnPatPRCeFWGXHC2gm7g/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1a6b2c2092488f221b0421a6a24b798e27d109ed1458823aa0009202760adc7ae3f03acb420db02c41ba9b4c84da8a8286f88f7b48027a350873b384174b9cd2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b04875b089e2cb5210b15126ffb9a7771a12052e4e5c7626c0bc87f3b38706aed700b99f05edef9d9a50fb2aa39f78c904a4e6208b3cdb51a98ec2bb807b172b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c71079979486698c305991f76789b212d16c3b38e3365f441460b1eb8291f1d1ae7ceb4295a180c081e2e99b2523b145739d151e3ea8f0c4039c20ab3cf25142"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 10:21:31.881935) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 10:21:31.882243) ========= 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.Ye42LVof2X/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.kl4aeK3DFq/Listeners TMPDIR=/var/folders/h_/my_ljhhj0k7_kt_82jk2vx6000000w/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.005214 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 10:21:31.930556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 10:21:31.930875) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 10:21:31.931192) ========= ========= Total master_lag: 0.61 =========