builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0209 starttime: 1468315450.48 results: success (0) buildid: 20160712005526 builduid: 33555fc9d27c47ab9d185a8a8f35c500 revision: 0d13d82daefda68120e464c2428a0f1124f2f780 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:10.479333) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:10.479788) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:10.480060) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:10.502667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:10.503029) ========= 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.HMbc0RH06T/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.RF9AVI03tq/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-12 02:24:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 173M=0s 2016-07-12 02:24:10 (173 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080351 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:10.604770) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:10.605083) ========= 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.HMbc0RH06T/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.RF9AVI03tq/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.020161 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:10.653651) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:10.654009) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0d13d82daefda68120e464c2428a0f1124f2f780 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0d13d82daefda68120e464c2428a0f1124f2f780 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HMbc0RH06T/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.RF9AVI03tq/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-12 02:24:10,736 truncating revision to first 12 chars 2016-07-12 02:24:10,736 Setting DEBUG logging. 2016-07-12 02:24:10,736 attempt 1/10 2016-07-12 02:24:10,736 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0d13d82daefd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 02:24:11,382 unpacking tar archive at: fx-team-0d13d82daefd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.923372 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:11.599241) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:11.599615) ========= script_repo_revision: 0d13d82daefda68120e464c2428a0f1124f2f780 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:11.600028) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:11.600335) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 02:24:11.618196) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-07-12 02:24:11.618472) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HMbc0RH06T/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.RF9AVI03tq/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:11 INFO - MultiFileLogger online at 20160712 02:24:11 in /builds/slave/test 02:24:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:24:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:24:11 INFO - {'append_to_log': False, 02:24:11 INFO - 'base_work_dir': '/builds/slave/test', 02:24:11 INFO - 'blob_upload_branch': 'Fx-Team', 02:24:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:24:11 INFO - 'branch': 'Fx-Team', 02:24:11 INFO - 'buildbot_json_path': 'buildprops.json', 02:24:11 INFO - 'config_files': ('talos/mac_config.py',), 02:24:11 INFO - 'default_actions': ('clobber', 02:24:11 INFO - 'read-buildbot-config', 02:24:11 INFO - 'download-and-extract', 02:24:11 INFO - 'populate-webroot', 02:24:11 INFO - 'create-virtualenv', 02:24:11 INFO - 'install', 02:24:11 INFO - 'run-tests'), 02:24:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:24:11 INFO - 'download_minidump_stackwalk': True, 02:24:11 INFO - 'download_symbols': 'ondemand', 02:24:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:24:11 INFO - 'tooltool.py': '/tools/tooltool.py', 02:24:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:24:11 INFO - '/tools/misc-python/virtualenv.py')}, 02:24:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:24:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:24:11 INFO - 'installer_path': 'installer.exe', 02:24:11 INFO - 'log_level': 'info', 02:24:11 INFO - 'log_name': 'talos', 02:24:11 INFO - 'log_to_console': True, 02:24:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:24:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:24:11 INFO - 'opt_config_files': (), 02:24:11 INFO - 'pip_index': False, 02:24:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:24:11 INFO - 'cmd': ('bash', 02:24:11 INFO - '-c', 02:24:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:24:11 INFO - 'enabled': True, 02:24:11 INFO - 'halt_on_failure': False, 02:24:11 INFO - 'name': 'check_screen_resolution'},), 02:24:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:24:11 INFO - 'cmd': ('bash', 02:24:11 INFO - '-c', 02:24:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:24:11 INFO - 'enabled': True, 02:24:11 INFO - 'halt_on_failure': False, 02:24:11 INFO - 'name': 'check_screen_resolution'},), 02:24:11 INFO - 'run_cmd_checks_enabled': True, 02:24:11 INFO - 'sps_profile': False, 02:24:11 INFO - 'sps_profile_interval': 0, 02:24:11 INFO - 'suite': 'dromaeojs', 02:24:11 INFO - 'system_bits': '32', 02:24:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:24:11 INFO - 'title': 't-yosemite-r7-0209', 02:24:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:24:11 INFO - 'use_talos_json': True, 02:24:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:24:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:24:11 INFO - 'work_dir': 'build'} 02:24:11 INFO - ##### 02:24:11 INFO - ##### Running clobber step. 02:24:11 INFO - ##### 02:24:11 INFO - Running pre-action listener: _resource_record_pre_action 02:24:11 INFO - Running main action method: clobber 02:24:11 INFO - rmtree: /builds/slave/test/build 02:24:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:24:12 INFO - Running post-action listener: _resource_record_post_action 02:24:12 INFO - ##### 02:24:12 INFO - ##### Finished clobber step (success) 02:24:12 INFO - ##### 02:24:12 INFO - ##### 02:24:12 INFO - ##### Running read-buildbot-config step. 02:24:12 INFO - ##### 02:24:12 INFO - Running pre-action listener: _resource_record_pre_action 02:24:12 INFO - Running main action method: read_buildbot_config 02:24:12 INFO - Using buildbot properties: 02:24:12 INFO - { 02:24:12 INFO - "project": "", 02:24:12 INFO - "product": "firefox", 02:24:12 INFO - "script_repo_revision": "production", 02:24:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:24:12 INFO - "repository": "", 02:24:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:24:12 INFO - "buildid": "20160712005526", 02:24:12 INFO - "pgo_build": "False", 02:24:12 INFO - "basedir": "/builds/slave/test", 02:24:12 INFO - "buildnumber": 97, 02:24:12 INFO - "slavename": "t-yosemite-r7-0209", 02:24:12 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:24:12 INFO - "platform": "yosemite_r7", 02:24:12 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:24:12 INFO - "branch": "fx-team", 02:24:12 INFO - "repo_path": "integration/fx-team", 02:24:12 INFO - "revision": "0d13d82daefda68120e464c2428a0f1124f2f780", 02:24:12 INFO - "stage_platform": "macosx64", 02:24:12 INFO - "builduid": "33555fc9d27c47ab9d185a8a8f35c500", 02:24:12 INFO - "slavebuilddir": "test" 02:24:12 INFO - } 02:24:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 02:24:12 INFO - Running post-action listener: _resource_record_post_action 02:24:12 INFO - ##### 02:24:12 INFO - ##### Finished read-buildbot-config step (success) 02:24:12 INFO - ##### 02:24:12 INFO - ##### 02:24:12 INFO - ##### Running download-and-extract step. 02:24:12 INFO - ##### 02:24:12 INFO - Running pre-action listener: _resource_record_pre_action 02:24:12 INFO - Running main action method: download_and_extract 02:24:12 INFO - mkdir: /builds/slave/test/build/tests 02:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:12 INFO - https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:24:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:24:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 02:24:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/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 02:24:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/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 02:24:13 INFO - Downloaded 1199 bytes. 02:24:13 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 02:24:13 INFO - Using the following test package requirements: 02:24:13 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:24:13 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:24:13 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 02:24:13 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:24:13 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 02:24:13 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:24:13 INFO - u'jsshell-mac.zip'], 02:24:13 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:24:13 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 02:24:13 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 02:24:13 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:24:13 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 02:24:13 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:24:13 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 02:24:13 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:24:13 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 02:24:13 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 02:24:13 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 02:24:13 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:24: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')]} 02:24:13 INFO - https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:24:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:24:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:24:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/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 02:24:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/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 02:24:14 INFO - Downloaded 18772120 bytes. 02:24:14 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'] 02:24:14 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 02:24:17 INFO - Return code: 0 02:24:17 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 02:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:17 INFO - https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:24:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:24:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 02:24:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/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 02:24:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/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 02:24:19 INFO - Downloaded 18772120 bytes. 02:24:19 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'] 02:24:19 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 02:24:22 INFO - Return code: 0 02:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:22 INFO - https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:24:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:24:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 02:24:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/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 02:24:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/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 02:24:23 INFO - Downloaded 14403333 bytes. 02:24:23 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'] 02:24:23 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 02:24:23 INFO - Return code: 0 02:24: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')]} 02:24:23 INFO - https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:24:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:24:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:24:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 02:24:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qc1vESgOQSqKS10Da2thnQ/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 02:24:26 INFO - Downloaded 112437197 bytes. 02:24:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:24:26 INFO - mkdir: /builds/slave/test/properties 02:24:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:24:26 INFO - Writing to file /builds/slave/test/properties/build_url 02:24:26 INFO - Contents: 02:24:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 02:24:27 INFO - Running post-action listener: _resource_record_post_action 02:24:27 INFO - Running post-action listener: set_extra_try_arguments 02:24:27 INFO - ##### 02:24:27 INFO - ##### Finished download-and-extract step (success) 02:24:27 INFO - ##### 02:24:27 INFO - ##### 02:24:27 INFO - ##### Running populate-webroot step. 02:24:27 INFO - ##### 02:24:27 INFO - Running pre-action listener: _resource_record_pre_action 02:24:27 INFO - Running main action method: populate_webroot 02:24:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:24:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:24:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:24:27 INFO - '--tppagecycles', 02:24:27 INFO - '7'], 02:24:27 INFO - 'tests': ['tsvgm']}}, 02:24:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:24:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:24:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:24:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:24:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:27 INFO - 'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:24:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:24:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:27 INFO - 'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['damp', 'tps']}, 02:24:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:27 INFO - 'tests': ['damp', 'tps']}, 02:24:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['dromaeo_dom']}, 02:24:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:24:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['basic_compositor_video']}, 02:24:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:24:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['a11yr', 02:24:27 INFO - 'ts_paint', 02:24:27 INFO - 'tpaint', 02:24:27 INFO - 'sessionrestore', 02:24:27 INFO - 'sessionrestore_no_auto_restore', 02:24:27 INFO - 'tabpaint']}, 02:24:27 INFO - 'other-e10s': {'tests': ['a11yr', 02:24:27 INFO - 'ts_paint', 02:24:27 INFO - 'tpaint', 02:24:27 INFO - 'sessionrestore', 02:24:27 INFO - 'sessionrestore_no_auto_restore', 02:24:27 INFO - 'tabpaint']}, 02:24:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:24:27 INFO - 'ts_paint', 02:24:27 INFO - 'tpaint', 02:24:27 INFO - 'sessionrestore', 02:24:27 INFO - 'sessionrestore_no_auto_restore', 02:24:27 INFO - 'tabpaint']}, 02:24:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:24:27 INFO - 'ts_paint', 02:24:27 INFO - 'tpaint', 02:24:27 INFO - 'sessionrestore', 02:24:27 INFO - 'sessionrestore_no_auto_restore', 02:24:27 INFO - 'tabpaint']}, 02:24:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['a11yr', 02:24:27 INFO - 'ts_paint', 02:24:27 INFO - 'tpaint', 02:24:27 INFO - 'sessionrestore', 02:24:27 INFO - 'sessionrestore_no_auto_restore', 02:24:27 INFO - 'tabpaint']}, 02:24:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['a11yr', 02:24:27 INFO - 'ts_paint', 02:24:27 INFO - 'tpaint', 02:24:27 INFO - 'sessionrestore', 02:24:27 INFO - 'sessionrestore_no_auto_restore', 02:24:27 INFO - 'tabpaint']}, 02:24:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['tsvgx', 02:24:27 INFO - 'tsvgr_opacity', 02:24:27 INFO - 'tart', 02:24:27 INFO - 'tscrollx', 02:24:27 INFO - 'cart']}, 02:24:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:24:27 INFO - 'tsvgr_opacity', 02:24:27 INFO - 'tart', 02:24:27 INFO - 'tscrollx', 02:24:27 INFO - 'cart']}, 02:24:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:27 INFO - 'talos_options': ['--disable-e10s'], 02:24:27 INFO - 'tests': ['tp5o']}, 02:24:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:27 INFO - 'tests': ['tp5o']}, 02:24:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:27 INFO - 'talos_options': ['--disable-e10s', 02:24:27 INFO - '--xperf_path', 02:24:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:24:27 INFO - 'tests': ['tp5n']}, 02:24:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:24:27 INFO - 'talos_options': ['--xperf_path', 02:24:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:24:27 INFO - 'tests': ['tp5n']}}, 02:24:27 INFO - 'talos.zip': {'path': '', 02:24:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:24:27 INFO - Running post-action listener: _resource_record_post_action 02:24:27 INFO - ##### 02:24:27 INFO - ##### Finished populate-webroot step (success) 02:24:27 INFO - ##### 02:24:27 INFO - ##### 02:24:27 INFO - ##### Running create-virtualenv step. 02:24:27 INFO - ##### 02:24:27 INFO - Running pre-action listener: _resource_record_pre_action 02:24:27 INFO - Running main action method: create_virtualenv 02:24:27 INFO - Creating virtualenv /builds/slave/test/build/venv 02:24:27 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 02:24:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:24:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:24:27 INFO - Using real prefix '/tools/python27' 02:24:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:24:28 INFO - Installing distribute.............................................................................................................................................................................................done. 02:24:31 INFO - Installing pip.................done. 02:24:31 INFO - Return code: 0 02:24:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:31 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 0x109d29a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dbe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c8d61850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, '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.HMbc0RH06T/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.RF9AVI03tq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:31 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 02:24:31 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 02:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:31 INFO - 'HOME': '/Users/cltbld', 02:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:31 INFO - 'LOGNAME': 'cltbld', 02:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:31 INFO - 'MOZ_NO_REMOTE': '1', 02:24:31 INFO - 'NO_EM_RESTART': '1', 02:24:31 INFO - 'PAGER': '/bin/cat', 02:24:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:31 INFO - 'PWD': '/builds/slave/test', 02:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:31 INFO - 'SHELL': '/bin/bash', 02:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:24:31 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:31 INFO - 'USER': 'cltbld', 02:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:31 INFO - 'XPC_FLAGS': '0x0', 02:24:31 INFO - 'XPC_SERVICE_NAME': '0', 02:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:31 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 02:24:31 INFO - Cleaning up... 02:24:31 INFO - Return code: 0 02:24:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:31 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 0x109d29a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dbe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c8d61850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, '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.HMbc0RH06T/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.RF9AVI03tq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:31 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 02:24:31 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 02:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:31 INFO - 'HOME': '/Users/cltbld', 02:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:31 INFO - 'LOGNAME': 'cltbld', 02:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:31 INFO - 'MOZ_NO_REMOTE': '1', 02:24:31 INFO - 'NO_EM_RESTART': '1', 02:24:31 INFO - 'PAGER': '/bin/cat', 02:24:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:31 INFO - 'PWD': '/builds/slave/test', 02:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:31 INFO - 'SHELL': '/bin/bash', 02:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:24:31 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:31 INFO - 'USER': 'cltbld', 02:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:31 INFO - 'XPC_FLAGS': '0x0', 02:24:31 INFO - 'XPC_SERVICE_NAME': '0', 02:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:31 INFO - Downloading/unpacking psutil>=3.1.1 02:24:31 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 02:24:31 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 02:24:31 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 02:24:31 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 02:24:31 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 02:24:31 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 02:24:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:24:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:24:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:24:34 INFO - Installing collected packages: psutil 02:24:34 INFO - Running setup.py install for psutil 02:24:34 INFO - building 'psutil._psutil_osx' extension 02:24:34 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 02:24:34 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 02:24:34 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 02:24:34 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 02:24:34 INFO - building 'psutil._psutil_posix' extension 02:24:34 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 02:24:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:24:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:24:34 INFO - ^ 02:24:34 INFO - 1 warning generated. 02:24:34 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 02:24:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:24:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:24:35 INFO - Successfully installed psutil 02:24:35 INFO - Cleaning up... 02:24:35 INFO - Return code: 0 02:24:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:35 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 0x109d29a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dbe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c8d61850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, '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.HMbc0RH06T/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.RF9AVI03tq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:35 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 02:24:35 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 02:24:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:24:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:35 INFO - 'HOME': '/Users/cltbld', 02:24:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:35 INFO - 'LOGNAME': 'cltbld', 02:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:35 INFO - 'MOZ_NO_REMOTE': '1', 02:24:35 INFO - 'NO_EM_RESTART': '1', 02:24:35 INFO - 'PAGER': '/bin/cat', 02:24:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:35 INFO - 'PWD': '/builds/slave/test', 02:24:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:35 INFO - 'SHELL': '/bin/bash', 02:24:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:24:35 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:24:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:35 INFO - 'USER': 'cltbld', 02:24:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:35 INFO - 'XPC_FLAGS': '0x0', 02:24:35 INFO - 'XPC_SERVICE_NAME': '0', 02:24:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:24:35 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 02:24:35 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 02:24:35 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 02:24:35 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 02:24:35 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 02:24:35 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 02:24:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:24:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:24:37 INFO - Installing collected packages: mozsystemmonitor 02:24:37 INFO - Running setup.py install for mozsystemmonitor 02:24:38 INFO - Successfully installed mozsystemmonitor 02:24:38 INFO - Cleaning up... 02:24:38 INFO - Return code: 0 02:24:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:24:38 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')]} 02:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:38 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')]} 02:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:38 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 0x109d29a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dbe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c8d61850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, '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.HMbc0RH06T/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.RF9AVI03tq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:38 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 02:24:38 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 02:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:38 INFO - 'HOME': '/Users/cltbld', 02:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:38 INFO - 'LOGNAME': 'cltbld', 02:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:38 INFO - 'MOZ_NO_REMOTE': '1', 02:24:38 INFO - 'NO_EM_RESTART': '1', 02:24:38 INFO - 'PAGER': '/bin/cat', 02:24:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:38 INFO - 'PWD': '/builds/slave/test', 02:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:38 INFO - 'SHELL': '/bin/bash', 02:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:24:38 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:38 INFO - 'USER': 'cltbld', 02:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:38 INFO - 'XPC_FLAGS': '0x0', 02:24:38 INFO - 'XPC_SERVICE_NAME': '0', 02:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:38 INFO - Downloading/unpacking jsonschema==2.5.1 02:24:38 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 02:24:38 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 02:24:38 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 02:24:38 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 02:24:38 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 02:24:38 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 02:24:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:24:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:24:40 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 02:24:40 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 02:24:40 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 02:24:40 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 02:24:40 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 02:24:40 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 02:24:41 INFO - Downloading functools32-3.2.3-2.tar.gz 02:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:24:41 INFO - warning: no files found matching '*.txt' 02:24:41 INFO - no previously-included directories found matching 'build' 02:24:41 INFO - no previously-included directories found matching 'dist' 02:24:41 INFO - no previously-included directories found matching '.git*' 02:24:41 INFO - Installing collected packages: jsonschema, functools32 02:24:41 INFO - Running setup.py install for functools32 02:24:41 INFO - warning: no files found matching '*.txt' 02:24:41 INFO - no previously-included directories found matching 'build' 02:24:41 INFO - no previously-included directories found matching 'dist' 02:24:41 INFO - no previously-included directories found matching '.git*' 02:24:41 INFO - Successfully installed jsonschema functools32 02:24:41 INFO - Cleaning up... 02:24:41 INFO - Return code: 0 02:24:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:24:41 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')]} 02:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:41 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')]} 02:24:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:41 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 0x109d29a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dbe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c8d61850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, '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.HMbc0RH06T/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.RF9AVI03tq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:41 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 02:24:41 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 02:24:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:24:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:41 INFO - 'HOME': '/Users/cltbld', 02:24:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:41 INFO - 'LOGNAME': 'cltbld', 02:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:41 INFO - 'MOZ_NO_REMOTE': '1', 02:24:41 INFO - 'NO_EM_RESTART': '1', 02:24:41 INFO - 'PAGER': '/bin/cat', 02:24:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:41 INFO - 'PWD': '/builds/slave/test', 02:24:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:41 INFO - 'SHELL': '/bin/bash', 02:24:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:24:41 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:24:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:41 INFO - 'USER': 'cltbld', 02:24:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:41 INFO - 'XPC_FLAGS': '0x0', 02:24:41 INFO - 'XPC_SERVICE_NAME': '0', 02:24:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:41 INFO - Downloading/unpacking blobuploader==1.2.4 02:24:41 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 02:24:41 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 02:24:41 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 02:24:41 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 02:24:41 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 02:24:41 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 02:24:44 INFO - Downloading blobuploader-1.2.4.tar.gz 02:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:24:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:24:44 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 02:24:44 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 02:24:44 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 02:24:44 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 02:24:44 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 02:24:44 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 02:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:24:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:45 INFO - Downloading docopt-0.6.1.tar.gz 02:24:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:24:45 INFO - Installing collected packages: blobuploader, requests, docopt 02:24:45 INFO - Running setup.py install for blobuploader 02:24:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:24:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:24:45 INFO - Running setup.py install for requests 02:24:46 INFO - Running setup.py install for docopt 02:24:46 INFO - Successfully installed blobuploader requests docopt 02:24:46 INFO - Cleaning up... 02:24:46 INFO - Return code: 0 02:24:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:24: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')]} 02:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24: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')]} 02:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:46 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 0x109d29a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dbe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c8d61850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, '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.HMbc0RH06T/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.RF9AVI03tq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:46 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 02:24:46 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 02:24:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:24:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:46 INFO - 'HOME': '/Users/cltbld', 02:24:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:46 INFO - 'LOGNAME': 'cltbld', 02:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:46 INFO - 'MOZ_NO_REMOTE': '1', 02:24:46 INFO - 'NO_EM_RESTART': '1', 02:24:46 INFO - 'PAGER': '/bin/cat', 02:24:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:46 INFO - 'PWD': '/builds/slave/test', 02:24:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:46 INFO - 'SHELL': '/bin/bash', 02:24:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:24:46 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:24:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:46 INFO - 'USER': 'cltbld', 02:24:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:46 INFO - 'XPC_FLAGS': '0x0', 02:24:46 INFO - 'XPC_SERVICE_NAME': '0', 02:24:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:24:46 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-RYXi4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:24:46 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-QraMPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:24:46 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-YZBiLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:24:46 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-H1s9OI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:24:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-4qelAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:24:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Sk6OUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:24:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-CNBeiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:24:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-qbbfe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:24:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-U1h8S7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:24:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-XjU73s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:24:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-mgmbz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:24:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-GVgfrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:24:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-89TR6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:24:48 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-i_XziR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:48 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-csIg6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:24:48 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-82OEuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:24:48 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-lEDOLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:24:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:24:48 INFO - Running setup.py install for manifestparser 02:24:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:24:48 INFO - Running setup.py install for mozcrash 02:24:48 INFO - Running setup.py install for mozdebug 02:24:48 INFO - Running setup.py install for mozdevice 02:24:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:24:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:24:49 INFO - Running setup.py install for mozfile 02:24:49 INFO - Running setup.py install for mozhttpd 02:24:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:24:49 INFO - Running setup.py install for mozinfo 02:24:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:24:49 INFO - Running setup.py install for mozInstall 02:24:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:24:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:24:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:24:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:24:49 INFO - Running setup.py install for mozleak 02:24:49 INFO - Running setup.py install for mozlog 02:24:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:24:49 INFO - Running setup.py install for moznetwork 02:24:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:24:50 INFO - Running setup.py install for mozprocess 02:24:50 INFO - Running setup.py install for mozprofile 02:24:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:24:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:24:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:24:50 INFO - Running setup.py install for mozrunner 02:24:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:24:50 INFO - Running setup.py install for mozscreenshot 02:24:50 INFO - Running setup.py install for moztest 02:24:50 INFO - Running setup.py install for mozversion 02:24:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:24:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:24:50 INFO - Cleaning up... 02:24:50 INFO - Return code: 0 02:24:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:24:50 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')]} 02:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:50 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')]} 02:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:50 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 0x109d29a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dbe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c8d61850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, '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.HMbc0RH06T/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.RF9AVI03tq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:50 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 02:24:50 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 02:24:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:24:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:50 INFO - 'HOME': '/Users/cltbld', 02:24:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:50 INFO - 'LOGNAME': 'cltbld', 02:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:50 INFO - 'MOZ_NO_REMOTE': '1', 02:24:50 INFO - 'NO_EM_RESTART': '1', 02:24:50 INFO - 'PAGER': '/bin/cat', 02:24:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:50 INFO - 'PWD': '/builds/slave/test', 02:24:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:50 INFO - 'SHELL': '/bin/bash', 02:24:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:24:50 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:24:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:50 INFO - 'USER': 'cltbld', 02:24:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:50 INFO - 'XPC_FLAGS': '0x0', 02:24:50 INFO - 'XPC_SERVICE_NAME': '0', 02:24:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:24:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-n2b80s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:24:51 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)) 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:24:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-SdQS12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:24:51 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)) 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:24:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-XYrxh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:24:51 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)) 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:24:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-8xxM1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:24:51 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)) 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:24:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-0W9Dwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:24:51 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)) 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:24:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-lzp4hv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:24:51 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)) 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:24:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Teisha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:24:51 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)) 02:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:24:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-VdP57_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:24:52 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)) 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:24:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-kvXOWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:24:52 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)) 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:24:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-pFBgHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:24:52 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)) 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:24:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-SSB1As-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:24:52 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)) 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:24:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-NscAbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:24:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-DRdC_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:24:52 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)) 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:24:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-bFe_Ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-ZTq4jC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:24:52 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)) 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:24:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-8j_k6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:24:52 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)) 02:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:24:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-6iKGxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:24:53 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)) 02:24:53 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)) 02:24:53 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)) 02:24:53 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)) 02:24:53 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)) 02:24:53 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)) 02:24:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:24:55 INFO - Downloading blessings-1.6.tar.gz 02:24:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:24:55 INFO - Installing collected packages: blessings 02:24:55 INFO - Running setup.py install for blessings 02:24:55 INFO - Successfully installed blessings 02:24:55 INFO - Cleaning up... 02:24:55 INFO - Return code: 0 02:24:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:24:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:24:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:24:56 INFO - Reading from file tmpfile_stdout 02:24:56 INFO - Current package versions: 02:24:56 INFO - blessings == 1.6 02:24:56 INFO - blobuploader == 1.2.4 02:24:56 INFO - docopt == 0.6.1 02:24:56 INFO - functools32 == 3.2.3-2 02:24:56 INFO - jsonschema == 2.5.1 02:24:56 INFO - manifestparser == 1.1 02:24:56 INFO - mozInstall == 1.12 02:24:56 INFO - mozcrash == 0.17 02:24:56 INFO - mozdebug == 0.1 02:24:56 INFO - mozdevice == 0.48 02:24:56 INFO - mozfile == 1.2 02:24:56 INFO - mozhttpd == 0.7 02:24:56 INFO - mozinfo == 0.9 02:24:56 INFO - mozleak == 0.1 02:24:56 INFO - mozlog == 3.2 02:24:56 INFO - moznetwork == 0.27 02:24:56 INFO - mozprocess == 0.23 02:24:56 INFO - mozprofile == 0.28 02:24:56 INFO - mozrunner == 6.12 02:24:56 INFO - mozscreenshot == 0.1 02:24:56 INFO - mozsystemmonitor == 0.3 02:24:56 INFO - moztest == 0.7 02:24:56 INFO - mozversion == 1.4 02:24:56 INFO - psutil == 3.1.1 02:24:56 INFO - requests == 1.2.3 02:24:56 INFO - wsgiref == 0.1.2 02:24:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:56 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 0x109d29a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dbe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c8d61850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, '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.HMbc0RH06T/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.RF9AVI03tq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:56 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 02:24:56 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 02:24:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:24:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:56 INFO - 'HOME': '/Users/cltbld', 02:24:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:56 INFO - 'LOGNAME': 'cltbld', 02:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:56 INFO - 'MOZ_NO_REMOTE': '1', 02:24:56 INFO - 'NO_EM_RESTART': '1', 02:24:56 INFO - 'PAGER': '/bin/cat', 02:24:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:56 INFO - 'PWD': '/builds/slave/test', 02:24:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:56 INFO - 'SHELL': '/bin/bash', 02:24:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:24:56 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:24:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:56 INFO - 'USER': 'cltbld', 02:24:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:56 INFO - 'XPC_FLAGS': '0x0', 02:24:56 INFO - 'XPC_SERVICE_NAME': '0', 02:24:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:56 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)) 02:24:56 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)) 02:24:56 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)) 02:24:56 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)) 02:24:56 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)) 02:24:56 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)) 02:24:56 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)) 02:24:56 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)) 02:24:56 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)) 02:24:56 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)) 02:24:56 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)) 02:24:56 INFO - Cleaning up... 02:24:56 INFO - Return code: 0 02:24:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:24:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:24:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:24:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d29a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109dbe618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1c8d61850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, '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.HMbc0RH06T/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.RF9AVI03tq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 02:24:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:24:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:24:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:56 INFO - 'HOME': '/Users/cltbld', 02:24:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:56 INFO - 'LOGNAME': 'cltbld', 02:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:56 INFO - 'MOZ_NO_REMOTE': '1', 02:24:56 INFO - 'NO_EM_RESTART': '1', 02:24:56 INFO - 'PAGER': '/bin/cat', 02:24:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:56 INFO - 'PWD': '/builds/slave/test', 02:24:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:56 INFO - 'SHELL': '/bin/bash', 02:24:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:24:56 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:24:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:56 INFO - 'USER': 'cltbld', 02:24:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:56 INFO - 'XPC_FLAGS': '0x0', 02:24:56 INFO - 'XPC_SERVICE_NAME': '0', 02:24:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:24:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:24:56 INFO - Cleaning up... 02:24:56 INFO - Return code: 0 02:24:56 INFO - Running post-action listener: _resource_record_post_action 02:24:56 INFO - Running post-action listener: _start_resource_monitoring 02:24:56 INFO - Starting resource monitoring. 02:24:56 INFO - ##### 02:24:56 INFO - ##### Finished create-virtualenv step (success) 02:24:56 INFO - ##### 02:24:56 INFO - ##### 02:24:56 INFO - ##### Running install step. 02:24:56 INFO - ##### 02:24:56 INFO - Running pre-action listener: _resource_record_pre_action 02:24:56 INFO - Running main action method: install 02:24:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:24:56 INFO - Reading from file tmpfile_stdout 02:24:56 INFO - Detecting whether we're running mozinstall >=1.0... 02:24:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:24:56 INFO - Reading from file tmpfile_stdout 02:24:56 INFO - Output received: 02:24:56 INFO - Usage: mozinstall [options] installer 02:24:56 INFO - Options: 02:24:56 INFO - -h, --help show this help message and exit 02:24:56 INFO - -d DEST, --destination=DEST 02:24:56 INFO - Directory to install application into. [default: 02:24:56 INFO - "/builds/slave/test"] 02:24:56 INFO - --app=APP Application being installed. [default: firefox] 02:24:56 INFO - mkdir: /builds/slave/test/build/application 02:24:56 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'] 02:24:56 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 02:25:31 INFO - Reading from file tmpfile_stdout 02:25:31 INFO - Output received: 02:25:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:25:31 INFO - Running post-action listener: _resource_record_post_action 02:25:31 INFO - ##### 02:25:31 INFO - ##### Finished install step (success) 02:25:31 INFO - ##### 02:25:31 INFO - ##### 02:25:31 INFO - ##### Running run-tests step. 02:25:31 INFO - ##### 02:25:31 INFO - Running pre-action listener: _resource_record_pre_action 02:25:31 INFO - Running main action method: run_tests 02:25:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:25:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:25:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:25:31 INFO - 2016-07-12 02:25:31.422 screenresolution[1919:12983] starting screenresolution argv=screenresolution get 02:25:31 INFO - 2016-07-12 02:25:31.425 screenresolution[1919:12983] Display 0: 1600x1200x32@60 02:25:31 INFO - 2016-07-12 02:25:31.430 screenresolution[1920:12985] starting screenresolution argv=screenresolution list 02:25:31 INFO - Available Modes on Display 0 02:25:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:25:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:25:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:25:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:25:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:25:31 INFO - Intel Iris: 02:25:31 INFO - Chipset Model: Intel Iris 02:25:31 INFO - Type: GPU 02:25:31 INFO - Bus: Built-In 02:25:31 INFO - VRAM (Dynamic, Max): 1536 MB 02:25:31 INFO - Vendor: Intel (0x8086) 02:25:31 INFO - Device ID: 0x0a2e 02:25:31 INFO - Revision ID: 0x0009 02:25:31 INFO - Displays: 02:25:31 INFO - Display: 02:25:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:25:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:25:31 INFO - Display Serial Number: 1600x1200 60 02:25:31 INFO - Main Display: Yes 02:25:31 INFO - Mirror: Off 02:25:31 INFO - Online: Yes 02:25:31 INFO - Rotation: Supported 02:25:31 INFO - Return code: 0 02:25:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:25:31 INFO - Python 2.7.3 02:25:31 INFO - Return code: 0 02:25:31 INFO - grabbing minidump binary from tooltool 02:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:25:31 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 0x109c8dd30>, '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 0x1099f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109c85b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:25:31 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 02:25:31 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 02:25:31 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 02:25:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:25:31 INFO - Return code: 0 02:25:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:25:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:25:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:25:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:25:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:25:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 02:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0209 --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 02:25:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMbc0RH06T/Render', 02:25:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:31 INFO - 'HOME': '/Users/cltbld', 02:25:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:31 INFO - 'LOGNAME': 'cltbld', 02:25:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:25:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:31 INFO - 'MOZ_NO_REMOTE': '1', 02:25:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:25:31 INFO - 'NO_EM_RESTART': '1', 02:25:31 INFO - 'PAGER': '/bin/cat', 02:25:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:31 INFO - 'PWD': '/builds/slave/test', 02:25:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:25:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:31 INFO - 'SHELL': '/bin/bash', 02:25:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RF9AVI03tq/Listeners', 02:25:31 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 02:25:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:31 INFO - 'USER': 'cltbld', 02:25:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:31 INFO - 'XPC_FLAGS': '0x0', 02:25:31 INFO - 'XPC_SERVICE_NAME': '0', 02:25:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 02:25:32 INFO - mozversion application_buildid: 20160712005526 02:25:32 INFO - mozversion application_changeset: 0d13d82daefda68120e464c2428a0f1124f2f780 02:25:32 INFO - mozversion application_display_name: Nightly 02:25:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:25:32 INFO - mozversion application_name: Firefox 02:25:32 INFO - mozversion application_remotingname: firefox 02:25:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:25:32 INFO - mozversion application_vendor: Mozilla 02:25:32 INFO - mozversion application_version: 50.0a1 02:25:32 INFO - mozversion platform_buildid: 20160712005526 02:25:32 INFO - mozversion platform_changeset: 0d13d82daefda68120e464c2428a0f1124f2f780 02:25:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:25:32 INFO - mozversion platform_version: 50.0a1 02:25:32 INFO - using testdate: 1468315532 02:25:32 INFO - actual date: 1468315532 02:25:32 INFO - starting webserver on 'localhost:52230' 02:25:32 INFO - SUITE-START | Running 2 tests 02:25:32 INFO - TEST-START | dromaeo_css 02:25:32 INFO - operating with platform_type : mac_ 02:25:32 INFO - Initialising browser for dromaeo_css test... 02:25:32 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp9Co8Dd/profile http://localhost:52230/getInfo.html) 02:25:34 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 02:25:34 INFO - PROCESS | 1930 | colorDepth:24 02:25:34 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 02:25:34 INFO - PROCESS | 1930 | __metrics 02:25:34 INFO - PROCESS | 1930 | 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" 02:25:34 INFO - PROCESS | 1930 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:25:35 INFO - TEST-INFO | 1930: exit 0 02:25:35 INFO - Browser initialized. 02:25:35 INFO - Running cycle 1/1 for dromaeo_css test... 02:25:35 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp9Co8Dd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:25:46 INFO - PROCESS | 1932 | RSS: Main: 243785728 02:25:46 INFO - PROCESS | 1932 | 02:26:38 INFO - PROCESS | 1932 | 1468315598375 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] 02:28:48 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:52230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52230/tests/dromaeo/cssquery-ext.html) 02:28:50 INFO - PROCESS | 1932 | RSS: Main: 385818624 02:28:50 INFO - PROCESS | 1932 | 02:31:53 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:52230/tests/dromaeo/cssquery-ext.html (next: http://localhost:52230/tests/dromaeo/cssquery-jquery.html) 02:31:53 INFO - PROCESS | 1932 | RSS: Main: 379383808 02:31:53 INFO - PROCESS | 1932 | 02:34:56 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:52230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52230/tests/dromaeo/cssquery-mootools.html) 02:34:57 INFO - PROCESS | 1932 | RSS: Main: 373424128 02:34:57 INFO - PROCESS | 1932 | 02:37:59 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:52230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52230/tests/dromaeo/cssquery-prototype.html) 02:38:00 INFO - PROCESS | 1932 | RSS: Main: 372932608 02:38:00 INFO - PROCESS | 1932 | 02:41:03 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:52230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52230/tests/dromaeo/cssquery-yui.html) 02:41:04 INFO - PROCESS | 1932 | RSS: Main: 346243072 02:41:04 INFO - PROCESS | 1932 | 02:44:07 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:52230/tests/dromaeo/cssquery-yui.html (next: http://localhost:52230/tests/dromaeo/cssquery-dojo.html) 02:44:07 INFO - PROCESS | 1932 | RSS: Main: 409698304 02:44:07 INFO - PROCESS | 1932 | 02:44:07 INFO - PROCESS | 1932 | __start_tp_report 02:44:07 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 02:44:07 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 02:44:07 INFO - PROCESS | 1932 | |i|pagename|runs| 02:44:07 INFO - PROCESS | 1932 | |0;dojo.html;3100.11646680696;3318.7842790218633;3320.1201681554376;3360.395128876801;3381.6787405196515;5563.833085007446;5619.522340601049;5638.689872057035;5710.229119068925;5755.107958266465;5646.2659854219;5661.490465858072;5763.625364351325;5770.422957704228;5783.566232532556;5064.442911279774;5086.440491545927;5142.100132476801;5160.14857548504;5224.660397074197;5214.843554693365;5257.185136303879;5269.262303277545;5280.894382112375;5298.735063246822;5081.212412076134;5089.312942752755;5109.3613298337705;5128.9230661540105;5157.5358217760395;5104.795808167706;5117.1556693145585;5125.487451254845;5129.743512824344;5144.717040562768;5112.590992720573;5134.948650513485;5136.871578210537;5151.562117220041;5175.948240517584;5524.889502209973;5591.412901645309;5690.743916523725;5721.5708821838525;5785.132230165466;5091.79632814687;6148.262208534959;6204.906926396107;6218.160548325964;6218.191635087455;6223.7821676241565;6268.780592679234;6288;6290.622562646256;6298.590591611531;5619.831405057855;5666.773329066791;5759.452851979056;5806.1000544915605;5807.477327040587;6278.717457714414;6288.465480434214;6296.307406185316;6308.968455157695;6312.5581209314905;6126.908096378558;6131.294901086343;6137;6138.416850399205;6142.6314421134875;5987.31145918225;6124.441964285778;6128.105930682418;6223.159873417121;6243.469305109116;6123.557162997653;6141.324454310023;6184.257889053252;6190.81427557174;6191.752329906843;6175.62946223228;6191.721372538156;6199.977003794414;6219.40915613016;6222.875542489125;4823.5658790709;4847.064871384187;4917.557419832161;4957.652964292465;4995.050940321327;4424.6902716810155;4462.286034234572;4479;4480.798364073625;4485.775711214426;4944.53026962438;5498.422665620133;5619.157126431068;5645.717714114277;5657;5145.76844042019;6125.571219981412;6156.860980718581;6214.25428948529;6219.346968568326;5678.665548337137;5689.402612725687;5695.288088988877;5699.487046165782;5712.971435142881;5459.617826752089;5477.096279113902;5477.260569823103;5482.506574408243;5483.067878460672;5184.844454666366;6205.131281620576;6254.968725156436;6269.467095296951;6352.364763523796;6168.1672974149715;6168.599041062316;6197.504199664107;6213.347598502273;6224.657643829632;6114.966544785144;6339.746410143543;6340.524460665376;6357.332480089524;6360.809175724736;6104.26748790148;6108.311716708011;6121.418465245794;6175.073738939195;6183.628982261079;6140.754369825157;6149.108379285068;6149.2620885494025;6167.074938759222;6178.845528861814;5269.656237659372;5940.821775346746;6075.453209211192;6077.604955677867;6103.786367477294;5902.734376952962;6081.483073938588;6158.076288556752;6166.044263139221;6171.876562468815;6000.399960003964;6011.819645410645;6034.517238620812;6060;6090.9390906090375;5900.468957793819;6044.909326360196;6065.636061836304;6144.170536977451;6152.692365381624;5958.314793798769;5964.493018093337;5978.461938425563;5979.40205979416;5994.190784244071 02:44:07 INFO - PROCESS | 1932 | |1;ext.html;509.681449094316;526.9973650131749;529.655723779543;549.3023859698184;564.9152627105933;30288.788448462063;40863.79568102159;42164.78917605407;42202.7889860551;42357.99999999996;42674.573254267445;42913;43053.7847310763;43055.35416968748;43157.136857262834;8589;9648.999999999982;9809.460479673651;9826.06652368024;9833.16418104462;8587.785305367353;11699.883001170007;11798.351090690008;11802.7639447211;12108.93945530271;11795.9383655471;11865.584704535342;11878.703032424215;11882.64352069435;11883.70290742734;8546.145385461405;11572.53709851605;11731.002864756421;11790.17468777186;11868.52525898963;11642.24325418845;11716.88283117175;11729.765404691858;11733.706657333634;11735.823962640567;40076.19847603032;40423.59576404228;40444.393334100016;40489.19021619551;40518.59481405207;3443.9655603444144;3453.861845526176;3587.157018100744;3623.800690961996;3766.133789228493;28481.145565633065;29592.70407295921;32473.51289730656;32603.18492037718;33030;3261.6901394367733;3324.700776930088;3336.9499457508155;3340.6659334066644;3447.3450044491465;492386.53806731093;492522;495625;497047;497074;58795.706021470476;59191.112133318034;59198.40801592015;59201.40798591959;59299.40700592939;60789;61668.383316167165;61719.382806171365;61795.07307390393;61932.45168870814;16224.67550649004;16492.83507164937;16513.91743041301;16525.25636346367;16541.090240036912;11671.649850504498;11920.761584768255;12652.240865548096;12701;12941.482340706267;15515.534533963999;15516.301766420538;15544.145072021147;15579.688406231811;15764.448244311394;5878.2358293421585;5896.646201227854;6651.603163335657;6741.224759152662;6805.6597170141295;782.4210084537515;890.4479222881855;914.446759710379;1022.897710228986;1103.4372470040337;30119.69880301213;30407.543886841715;30520.69479305223;31143.68856311453;31355.843220784205;1060.2154405739698;1107.1198397274025;1117.5027112934665;1167.9532818687157;1181;15407.46073887431;15618.90667653276;15922.522324330288;15958.521244362688;16326;607164.9641751852;613568;616938;621554;624308;58327.708361457924;58412.12381814276;58567;58592;58691.41308586945;1097.0729733375285;1280.187081203424;1417.6951955329653;1447.0666420159148;1448.0732331307972;29918.80324786984;31562.210944725648;31718.524222136224;31765.68234317627;32345.51481727729;48545.27182092201;49363.506364937326;49486.75256623838;49533.50466495289;49547.00905981932;497973;499553;505400;506332;513038;34249.82875085609;40366.79816600898;40373.39439908345;41233.58766412298;42405;38241;48522.5147748518;49596.25605615846;49596.75201623969;49798;38500;39543.80228098841;40056.798296051165;40871.1825763489;41004.35816164794;39906.80046599748;40131;40813.38779918244;40952.59047409607;41321.96695082647;32216.35567288688;38470;39396;39447.81656550308;41187.794061029505;33856.66143338535;34819.40832168816;35386.64613353936;35421.82289088538;36152.63847361598;22486.662700059183;23939.401514962265;25639.487210256062;25704.228873133838;25877 02:44:07 INFO - PROCESS | 1932 | |2;jquery.html;335.89923023093075;353.90621485306417;371.6673577148452;385.452657226738;406.4594089860484;15741.291641876114;17038.65922681547;21254;21385.99999999998;21419.357419277407;21419.571608567818;21946.561068778614;22370.88814555929;22586.43533911653;22621.868906554646;5211.739413029352;5492.697901615409;5560.77756889724;6041.154238406627;6058.4244496772735;4160.375943608453;4403.405540252057;5905.379935106816;6337.144485494446;6667.233268174174;6352.237731472207;6627.536072474929;6639.568428052161;6644.767433139841;6660.367265109807;6616.669166541702;6640.900386494206;6647.76732814354;6648.202215734119;6649.501287403464;5259.421463638997;5563.248961390201;5572.86067848307;5787.855303617401;5791.189233507313;13743.931280343635;13847.892168626486;13924.094933829268;13999.160050396908;14111.435542578285;2307.492351682627;2350.9059637614646;2384.1536254629705;2419.27421773469;2470.962935555968;10033.849492257623;10604.787904241872;10731.141508679288;14934.32795524204;16369.181540922906;1817.836394724468;1867.6078023615057;1962.2641509433963;1967.3507742444958;1986.8708533945248;105645;107358;109652;120825;122724;11390.487428065757;11609.477573509044;11637.47631356591;11707.414629268502;11824.349660768741;14670;14757;14781.92609036948;14791.408343666348;14810.185439800698;8632.870506942401;8645.567721613894;8674.739757807276;8715.389922705477;8762.299016078698;8691.521966291915;8704.347173961978;8725.694600688945;8798.560071996375;8804.603792829337;4602.125596136723;4610.907781844345;4628.83799067031;4632.397788287569;4683.2038553446;1495.4541592318726;1597.5526852481323;1770.1408721111766;1824.5256233379416;1976.4861136104716;867.8568036274071;883.6951251818112;936.9672061477816;1001.369137443406;1019.9439030853374;17682.381116660854;23688.40778980539;23712.40718982039;24022.15922442741;24571.3857153569;922.8477301245354;963.3689933093834;970.2092794372705;1009.7576581620358;1032.8450732390052;11593.768124637629;11835.99394051498;11993.400329983466;12045.819312710317;12098.697532561757;513946.43026784627;522849;522930;607941;615013;82207.17792822115;141677;142445.57554424324;151635;151736.72394914084;943.235978857128;963.816874793787;993.3096497934011;994.472929347447;1014.8224060789332;19282.606651256992;19922.79063774523;19957.01017161189;20000.199006955478;20002.98433225566;53047;56788;59624;60129.398706012376;60479.69760151171;87024.5648771752;88139;88695;89115;90399;8089.191080891864;8227.876581851397;8368.874466883124;8560.785980350542;8615.655373785228;11737.178397512434;13192.604221873358;13196.538121165713;13302.268375239455;13378.063535552807;11367.48846301935;11379.317240965569;11389.202755807346;11399.544018239509;11407.144464165054;10102.494875256354;11011.339319640847;11176.552937882394;11240.494177762184;11439.485223164811;8700.859692450882;10473;11295.943520282346;11414.315141091562;11680.824787628022;7903.960480197562;8090.79773005656;8312.6674933002;8348.87476687838;8379.790505237417;10909.727256818644;11318.547258109913;11320.717230417788;11331.433428328386;11398 02:44:07 INFO - PROCESS | 1932 | |3;mootools.html;1367.4188469900294;1508.411719429422;1700.2433916906973;1878.1360574135897;1905.8951757653324;7883.172266911978;8142.552159631218;8317.958410207948;8855.690050848221;8872.112788721133;8308.667653293876;8563.400561960665;8587.184217499344;8728.258098061657;8735.432196907197;2277.339571524256;2345.4488195274184;2348.236823032513;2349.048635302705;2357.599208134621;2059.093998640601;2071.264701031135;2098.1083039708137;2160.708304378912;2162.4593851537115;2121.819645330149;2135.135270215565;2250.0437314141504;2331.277304035746;2332.4402143485527;2301.6662583925236;2331.2773040357542;2336.6611841283;2340.754220806808;2343.7656234376595;2254.109626697462;2324.628059510487;2334.9299521014227;2338.310198491432;2342.133410638075;8704.521251331174;8930.910690893073;8960.01439841617;8964.238039766627;8968.192862642329;5194.142966410538;5295.285136506599;5329.467053294678;5331.93361327736;5333.57331413486;3407.4718418645148;3448.9827550862337;3476.8609255629744;3510.6489351064943;3519.964800351989;1084.5553323137476;1085.0451602589749;1090.7600327927844;1098.8022156011914;1105.8783533811354;11470.139739519569;11535.884641153565;11536.826947595793;11551.480183391683;11556.248843825124;4306.0957198987935;4329.826806927751;4532.98007948209;4547.909041819211;4555.362249285103;9125.133112354315;9130.543472826332;9146.54267286633;9150.496722680316;9150.633974641072;4264.616184543406;5183.50756678115;5185.299984502044;5190.948090519122;5195.766190521435;4759.190937540627;4865.172920603435;4867.367242258554;4871.732054737024;4875.975620121877;3559.5728512578658;3585.982070089685;3616.168281295264;3666.138457462494;3668.0463079599494;1032.055669062814;1038.9064984151462;1040.9167266618651;1043.1446214104512;1075.6772968109535;629.7638385605399;880.221004411088;921.7280902133887;942.2838642631513;948.2177203806887;5859.414058594191;5928.67392293428;6049.455549000611;6068.423499767515;6207.534434917399;896.4825717037104;913.4017218721748;936.9740134552654;961.4904100826573;983.0759086458706;3823.5794062653085;3900.356441187229;4032.536258330271;4140.4617399737845;4148.543660197376;10295.43375114361;10723.785524289626;10768.030877220932;10770.138388928866;10784.622538211124;9642.903570964201;10170.186385089175;10557.419341936115;10747.946260268649;10762.56949722018;6924.34218749208;8076.27313541784;8279.668813247523;8296.626651800683;8305.95847020761;5524.83978364548;5865;6050.606710563799;6132.141500190066;6265.467435268053;9839.950800245953;10036.096751292418;10048.849267261143;10062.748431289276;10065.899341006789;8635.093315202064;9746.976567460599;10119.797604048024;10207.387556746618;10237.23220758461;5911.674857882857;6990.9300906990275;7078;7079.681414336264;7169.605671688108;8194.918050819415;8444.704435344733;8446.577671116542;8448.957755211184;8450.619722112397;5948.533812088535;7040.894386584103;7078.115235595551;7095.325944035309;7192;7162.534435261691;7192.964035179791;7225;7257.52826066304;7302;7027.15674119109;7054.365107140382;7092.680829362588;7108.46686498525;7113.75101871432;4874.805007799649;4898.8775280618265;4975.502449754996;4976.527229913152;5057.772400242072;4937.456879743298;4988.625853061108;5020.648554601143;5164.04465173944;5169.379674439091 02:44:07 INFO - PROCESS | 1932 | |4;prototype.html;250.3728160956803;264.4182797844741;308.42786630799867;318.5317583152765;322.39389946899826;4493.438320209973;5339.839804805857;5361.544268737162;5385.946140538598;5426.403095659479;4150.920760602243;5204.817831375903;5287.625217443464;5350.732463376826;5354.839354819352;3112.4553203189407;3751.1559899022773;3860.613938606131;3892.2020985698005;4045.3931910213555;3353.983230083846;4031.717779755418;4043.5552089270304;4062.1469491406842;4101.302778527657;3350.48067549529;3394.353738436856;4050.554439011721;4106.568810274923;4113.9177216455655;3845.5193100862393;3929.25344184604;3979.5622481527007;4078.5921407859123;4093.774842383668;2676.1703871799805;3528.7353448491467;3543.5039094526787;3560.0209942265824;3592.3533763922483;5149.124648809711;5173.379194496647;5189.974050129726;5210.557102646279;5234.502722241381;4356.302991521373;4404.889877753049;4581.473130589991;4654.534546545318;4743.35964644772;5597.888042239173;5602.60781745278;5627.876528526666;5632.831015069554;5650.632708873951;3945.230680017398;3973.5099337748343;3974.960250397488;3980.925150209427;3983.223271462066;5283.920741188886;5330.413654498002;5777.162311464814;5863.2670916135485;5874.475314811122;3568.8750893718593;3851.441540976543;3967.107400834847;3974.7217694761666;4007.6793856491413;3326.3679900818765;3743.494628225155;4092.836286548564;4115.074108325663;4124.391487319947;4412.146898302721;4679.836205732783;4686.687727436323;4722.3318824863845;4727.716337019721;5297.629165958423;5399.892002160013;5580.2466666999435;5602.2717046783655;5741.282339707536;1188.8157335612993;1214.6538236602703;1406.4936622815778;1408.5140626483847;1415.3489394878266;730.6711979609197;824.4105464592725;873.9082396348292;876.7325965580432;882.5102068352127;4927.975360123248;5015.448300686921;5096.941834899105;5173;5185;4781.6891902026255;5693.4875861172695;5714.342850572238;5748.741306641211;5767.452092051249;4366.454193225847;4573.885652858638;4852.441969173519;5103.974480127576;5177.844664660066;2588.2235329401105;2993.985030074836;2994.8053376530893;2998.78090213245;3012.8041677291344;5777.364489906107;5887.528997680175;5893.793717219963;5909.881802364014;5915.231019967377;4920.803167873317;5333.333333333333;5537.695426751487;5967.104934259809;6022.217111775529;5309.3894202166475;5514.338279406497;5987.6108052976415;6007.36922623127;6062.636241825505;5496.697681627549;5729.427057294237;5810.389909059488;5870.26621672291;5872.295324560909;5035.874103147451;5725.79758250772;5760.423957604206;5795.130730390475;5797.695518508302;5462.617616766788;5702.771889124555;5787.543591434656;6030.7286172121485;6062.514998800174;3398.014575773097;3626.4560315951762;3831.885043448701;3886.6696330812197;3888.8833334999995;5728.455796699307;5736.541076713769;5738.397468265939;5762.913556296743;5768.567357448124;4749.548792864673;5032.119379108714;5600.663960162403;5608.850185712001;5641.435856414326;4801.7118972861745;5329;5674.2623458950075;5684.744186511534;5685.090385538294;4668.486466488615;5274.235235890851;5561.527270181918;5583.497485226349;5591.356993945586;4506.174999125359;5335.611886452053;5557.583181261502;5687.829365119053;5711.657300561979;4423.823047078209;5545;5552.861178470571;5561.972190139023;5603.607747457801 02:44:07 INFO - PROCESS | 1932 | |5;yui.html;747.349805669068;839.0895877972399;840.5293035899897;861.1259571534891;879.3580686099151;15238.466653667125;16125.83874161258;16596.751048734262;16623.66752664949;16632.833671663295;15035;15147.54557363278;15147.848521514781;15188;15456.922715386407;716.3874886971643;737.6680493777794;881.607684580362;899.5277479323343;904.8778414914001;715.4205093873953;719.543090137761;719.8596273726625;721.90976127984;862.2196911794836;948.5494390164686;956.741679746468;957.3203025851653;958.4441024205979;959.2517836087862;793.8769490728918;948.7390967483929;952.766572189811;954.0650162840456;955.5938726041475;537.5484592941948;564.4496615799562;564.8220810444697;692.0829900382014;697.9336962988508;12608.621741347773;12646.114771965875;15520.844791552054;15832.97085689438;15845.603859903595;14509.782353264709;15225.162616056112;15316.693666126615;15362.308696108701;15375.461858834888;10315.381077135396;12060;12153.878461215363;12325.383730813513;12526.311052892088;3300.884469043572;3797.9810100949594;3856.325143099946;3927.56796752357;4060.3909413587903;112303.43848280788;112597;112621.31068033986;113726;116806.99999999916;11678.941605291919;11688.934635101752;11700.414979251003;11788;12260.203086799329;20389.388318350473;24184.51630967406;24566.877165614416;24576.262712118663;24643.3839154019;8243.83512329762;8254.215849494289;8257.504549726915;8418.158184181655;8464.111268316738;4404.2512772828695;4482.417285752831;4500.549945005473;4547.272436410159;4604.97697511517;3552.91117722059;3699.8890033299044;3711.758735682226;3717.4609681596557;3726.5341832270965;694.2779509310361;727.5634619228526;745.7240820896195;746.2015643261766;750.3959312753286;520.0015969338879;595.4105435618707;607.4623957797381;617.4905702795212;689.8896176611718;8799.516026618598;9019.819603607892;9329.813403731887;9597.424154550748;9636.277279204087;645.6932956845461;649.878958796073;674.8110529051874;677.9118714567074;684.6268783512998;4442.088891310432;4469.307257375112;4514.458265008155;4557.726536407826;4598.333241680005;126228;165246;165715;169745;171230;145138;145925;146084.26957865356;147193;152622;84140;123119;123125;123398;125550.37224813817;7519.248075192437;7911.208879112043;8680.392372533863;8893.107841422949;9063.32025098107;51850.74074629754;66267.00599491107;66378.33621663721;66404.66797665981;68775.31224687689;63030.36969630245;77379.61310193413;101646;101754;102715;12358;12533.310667912987;12536.247825130522;12888.48446062147;12918.418671160009;12704.36478176106;12922.008679479231;13043.543475978295;13057.151285166434;13304.002199834858;12446.751064978469;12508.562200322946;12861.935690321488;12871.742565148832;12883;12443.626691199279;12835.844773970388;13135;13605.863941360856;14069.226192559507;11642.301461912313;13814;13882.861171388156;13942.233177175352;13961.511347102803;10626.628068017582;11168.609098681507;11281.266717663326;11563.30620162793;11605.6518304451;9272.350935434455;9645.42127472354;9752.951235243778;9778;9886.555105020147 02:44:07 INFO - PROCESS | 1932 | __end_tp_report 02:44:07 INFO - PROCESS | 1932 | __start_cc_report 02:44:07 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,1382 02:44:07 INFO - PROCESS | 1932 | __end_cc_report 02:44:07 INFO - PROCESS | 1932 | __startTimestamp1468316647399__endTimestamp 02:44:07 INFO - PROCESS | 1932 | 02:44:07 INFO - PROCESS | 1932 | ------- Summary: start ------- 02:44:07 INFO - PROCESS | 1932 | Number of tests: 6 02:44:07 INFO - PROCESS | 1932 | 02:44:07 INFO - PROCESS | 1932 | [#0] dojo.html Cycles:165 Average:5695.39 Median:5958.31 stddev:645.90 (10.8%) stddev-sans-first:614.96 02:44:07 INFO - PROCESS | 1932 | Values: 3100.1 3318.8 3320.1 3360.4 3381.7 5563.8 5619.5 5638.7 5710.2 5755.1 5646.3 5661.5 5763.6 5770.4 5783.6 5064.4 5086.4 5142.1 5160.1 5224.7 5214.8 5257.2 5269.3 5280.9 5298.7 5081.2 5089.3 5109.4 5128.9 5157.5 5104.8 5117.2 5125.5 5129.7 5144.7 5112.6 5134.9 5136.9 5151.6 5175.9 5524.9 5591.4 5690.7 5721.6 5785.1 5091.8 6148.3 6204.9 6218.2 6218.2 6223.8 6268.8 6288.0 6290.6 6298.6 5619.8 5666.8 5759.5 5806.1 5807.5 6278.7 6288.5 6296.3 6309.0 6312.6 6126.9 6131.3 6137.0 6138.4 6142.6 5987.3 6124.4 6128.1 6223.2 6243.5 6123.6 6141.3 6184.3 6190.8 6191.8 6175.6 6191.7 6200.0 6219.4 6222.9 4823.6 4847.1 4917.6 4957.7 4995.1 4424.7 4462.3 4479.0 4480.8 4485.8 4944.5 5498.4 5619.2 5645.7 5657.0 5145.8 6125.6 6156.9 6214.3 6219.3 5678.7 5689.4 5695.3 5699.5 5713.0 5459.6 5477.1 5477.3 5482.5 5483.1 5184.8 6205.1 6255.0 6269.5 6352.4 6168.2 6168.6 6197.5 6213.3 6224.7 6115.0 6339.7 6340.5 6357.3 6360.8 6104.3 6108.3 6121.4 6175.1 6183.6 6140.8 6149.1 6149.3 6167.1 6178.8 5269.7 5940.8 6075.5 6077.6 6103.8 5902.7 6081.5 6158.1 6166.0 6171.9 6000.4 6011.8 6034.5 6060.0 6090.9 5900.5 6044.9 6065.6 6144.2 6152.7 5958.3 5964.5 5978.5 5979.4 5994.2 02:44:07 INFO - PROCESS | 1932 | 02:44:07 INFO - PROCESS | 1932 | [#1] ext.html Cycles:180 Average:68316.91 Median:38948.00 stddev:144250.91 (370.4%) stddev-sans-first:144565.73 02:44:07 INFO - PROCESS | 1932 | Values: 509.7 527.0 529.7 549.3 564.9 30288.8 40863.8 42164.8 42202.8 42358.0 42674.6 42913.0 43053.8 43055.4 43157.1 8589.0 9649.0 9809.5 9826.1 9833.2 8587.8 11699.9 11798.4 11802.8 12108.9 11795.9 11865.6 11878.7 11882.6 11883.7 8546.1 11572.5 11731.0 11790.2 11868.5 11642.2 11716.9 11729.8 11733.7 11735.8 40076.2 40423.6 40444.4 40489.2 40518.6 3444.0 3453.9 3587.2 3623.8 3766.1 28481.1 29592.7 32473.5 32603.2 33030.0 3261.7 3324.7 3336.9 3340.7 3447.3 492386.5 492522.0 495625.0 497047.0 497074.0 58795.7 59191.1 59198.4 59201.4 59299.4 60789.0 61668.4 61719.4 61795.1 61932.5 16224.7 16492.8 16513.9 16525.3 16541.1 11671.6 11920.8 12652.2 12701.0 12941.5 15515.5 15516.3 15544.1 15579.7 15764.4 5878.2 5896.6 6651.6 6741.2 6805.7 782.4 890.4 914.4 1022.9 1103.4 30119.7 30407.5 30520.7 31143.7 31355.8 1060.2 1107.1 1117.5 1168.0 1181.0 15407.5 15618.9 15922.5 15958.5 16326.0 607165.0 613568.0 616938.0 621554.0 624308.0 58327.7 58412.1 58567.0 58592.0 58691.4 1097.1 1280.2 1417.7 1447.1 1448.1 29918.8 31562.2 31718.5 31765.7 32345.5 48545.3 49363.5 49486.8 49533.5 49547.0 497973.0 499553.0 505400.0 506332.0 513038.0 34249.8 40366.8 40373.4 41233.6 42405.0 38241.0 48522.5 49596.3 49596.8 49798.0 38500.0 39543.8 40056.8 40871.2 41004.4 39906.8 40131.0 40813.4 40952.6 41322.0 32216.4 38470.0 39396.0 39447.8 41187.8 33856.7 34819.4 35386.6 35421.8 36152.6 22486.7 23939.4 25639.5 25704.2 25877.0 02:44:07 INFO - PROCESS | 1932 | 02:44:07 INFO - PROCESS | 1932 | [#2] jquery.html Cycles:180 Average:34293.25 Median:13019.66 stddev:93947.69 (721.6%) stddev-sans-first:94176.64 02:44:07 INFO - PROCESS | 1932 | Values: 335.9 353.9 371.7 385.5 406.5 15741.3 17038.7 21254.0 21386.0 21419.4 21419.6 21946.6 22370.9 22586.4 22621.9 5211.7 5492.7 5560.8 6041.2 6058.4 4160.4 4403.4 5905.4 6337.1 6667.2 6352.2 6627.5 6639.6 6644.8 6660.4 6616.7 6640.9 6647.8 6648.2 6649.5 5259.4 5563.2 5572.9 5787.9 5791.2 13743.9 13847.9 13924.1 13999.2 14111.4 2307.5 2350.9 2384.2 2419.3 2471.0 10033.8 10604.8 10731.1 14934.3 16369.2 1817.8 1867.6 1962.3 1967.4 1986.9 105645.0 107358.0 109652.0 120825.0 122724.0 11390.5 11609.5 11637.5 11707.4 11824.3 14670.0 14757.0 14781.9 14791.4 14810.2 8632.9 8645.6 8674.7 8715.4 8762.3 8691.5 8704.3 8725.7 8798.6 8804.6 4602.1 4610.9 4628.8 4632.4 4683.2 1495.5 1597.6 1770.1 1824.5 1976.5 867.9 883.7 937.0 1001.4 1019.9 17682.4 23688.4 23712.4 24022.2 24571.4 922.8 963.4 970.2 1009.8 1032.8 11593.8 11836.0 11993.4 12045.8 12098.7 513946.4 522849.0 522930.0 607941.0 615013.0 82207.2 141677.0 142445.6 151635.0 151736.7 943.2 963.8 993.3 994.5 1014.8 19282.6 19922.8 19957.0 20000.2 20003.0 53047.0 56788.0 59624.0 60129.4 60479.7 87024.6 88139.0 88695.0 89115.0 90399.0 8089.2 8227.9 8368.9 8560.8 8615.7 11737.2 13192.6 13196.5 13302.3 13378.1 11367.5 11379.3 11389.2 11399.5 11407.1 10102.5 11011.3 11176.6 11240.5 11439.5 8700.9 10473.0 11295.9 11414.3 11680.8 7904.0 8090.8 8312.7 8348.9 8379.8 10909.7 11318.5 11320.7 11331.4 11398.0 02:44:07 INFO - PROCESS | 1932 | 02:44:07 INFO - PROCESS | 1932 | [#3] mootools.html Cycles:180 Average:5596.35 Median:4976.01 stddev:3148.43 (63.3%) stddev-sans-first:3141.23 02:44:07 INFO - PROCESS | 1932 | Values: 1367.4 1508.4 1700.2 1878.1 1905.9 7883.2 8142.6 8318.0 8855.7 8872.1 8308.7 8563.4 8587.2 8728.3 8735.4 2277.3 2345.4 2348.2 2349.0 2357.6 2059.1 2071.3 2098.1 2160.7 2162.5 2121.8 2135.1 2250.0 2331.3 2332.4 2301.7 2331.3 2336.7 2340.8 2343.8 2254.1 2324.6 2334.9 2338.3 2342.1 8704.5 8930.9 8960.0 8964.2 8968.2 5194.1 5295.3 5329.5 5331.9 5333.6 3407.5 3449.0 3476.9 3510.6 3520.0 1084.6 1085.0 1090.8 1098.8 1105.9 11470.1 11535.9 11536.8 11551.5 11556.2 4306.1 4329.8 4533.0 4547.9 4555.4 9125.1 9130.5 9146.5 9150.5 9150.6 4264.6 5183.5 5185.3 5190.9 5195.8 4759.2 4865.2 4867.4 4871.7 4876.0 3559.6 3586.0 3616.2 3666.1 3668.0 1032.1 1038.9 1040.9 1043.1 1075.7 629.8 880.2 921.7 942.3 948.2 5859.4 5928.7 6049.5 6068.4 6207.5 896.5 913.4 937.0 961.5 983.1 3823.6 3900.4 4032.5 4140.5 4148.5 10295.4 10723.8 10768.0 10770.1 10784.6 9642.9 10170.2 10557.4 10747.9 10762.6 6924.3 8076.3 8279.7 8296.6 8306.0 5524.8 5865.0 6050.6 6132.1 6265.5 9840.0 10036.1 10048.8 10062.7 10065.9 8635.1 9747.0 10119.8 10207.4 10237.2 5911.7 6990.9 7078.0 7079.7 7169.6 8194.9 8444.7 8446.6 8449.0 8450.6 5948.5 7040.9 7078.1 7095.3 7192.0 7162.5 7193.0 7225.0 7257.5 7302.0 7027.2 7054.4 7092.7 7108.5 7113.8 4874.8 4898.9 4975.5 4976.5 5057.8 4937.5 4988.6 5020.6 5164.0 5169.4 02:44:07 INFO - PROCESS | 1932 | 02:44:07 INFO - PROCESS | 1932 | [#4] prototype.html Cycles:180 Average:4540.29 Median:5175.61 stddev:1414.63 (27.3%) stddev-sans-first:1381.47 02:44:07 INFO - PROCESS | 1932 | Values: 250.4 264.4 308.4 318.5 322.4 4493.4 5339.8 5361.5 5385.9 5426.4 4150.9 5204.8 5287.6 5350.7 5354.8 3112.5 3751.2 3860.6 3892.2 4045.4 3354.0 4031.7 4043.6 4062.1 4101.3 3350.5 3394.4 4050.6 4106.6 4113.9 3845.5 3929.3 3979.6 4078.6 4093.8 2676.2 3528.7 3543.5 3560.0 3592.4 5149.1 5173.4 5190.0 5210.6 5234.5 4356.3 4404.9 4581.5 4654.5 4743.4 5597.9 5602.6 5627.9 5632.8 5650.6 3945.2 3973.5 3975.0 3980.9 3983.2 5283.9 5330.4 5777.2 5863.3 5874.5 3568.9 3851.4 3967.1 3974.7 4007.7 3326.4 3743.5 4092.8 4115.1 4124.4 4412.1 4679.8 4686.7 4722.3 4727.7 5297.6 5399.9 5580.2 5602.3 5741.3 1188.8 1214.7 1406.5 1408.5 1415.3 730.7 824.4 873.9 876.7 882.5 4928.0 5015.4 5096.9 5173.0 5185.0 4781.7 5693.5 5714.3 5748.7 5767.5 4366.5 4573.9 4852.4 5104.0 5177.8 2588.2 2994.0 2994.8 2998.8 3012.8 5777.4 5887.5 5893.8 5909.9 5915.2 4920.8 5333.3 5537.7 5967.1 6022.2 5309.4 5514.3 5987.6 6007.4 6062.6 5496.7 5729.4 5810.4 5870.3 5872.3 5035.9 5725.8 5760.4 5795.1 5797.7 5462.6 5702.8 5787.5 6030.7 6062.5 3398.0 3626.5 3831.9 3886.7 3888.9 5728.5 5736.5 5738.4 5762.9 5768.6 4749.5 5032.1 5600.7 5608.9 5641.4 4801.7 5329.0 5674.3 5684.7 5685.1 4668.5 5274.2 5561.5 5583.5 5591.4 4506.2 5335.6 5557.6 5687.8 5711.7 4423.8 5545.0 5552.9 5562.0 5603.6 02:44:07 INFO - PROCESS | 1932 | 02:44:07 INFO - PROCESS | 1932 | [#5] yui.html Cycles:180 Average:25932.43 Median:3714.61 stddev:42738.52 (1150.6%) stddev-sans-first:42816.57 02:44:07 INFO - PROCESS | 1932 | Values: 747.3 839.1 840.5 861.1 879.4 15238.5 16125.8 16596.8 16623.7 16632.8 15035.0 15147.5 15147.8 15188.0 15456.9 716.4 737.7 881.6 899.5 904.9 715.4 719.5 719.9 721.9 862.2 948.5 956.7 957.3 958.4 959.3 793.9 948.7 952.8 954.1 955.6 537.5 564.4 564.8 692.1 697.9 12608.6 12646.1 15520.8 15833.0 15845.6 14509.8 15225.2 15316.7 15362.3 15375.5 10315.4 12060.0 12153.9 12325.4 12526.3 3300.9 3798.0 3856.3 3927.6 4060.4 112303.4 112597.0 112621.3 113726.0 116807.0 11678.9 11688.9 11700.4 11788.0 12260.2 20389.4 24184.5 24566.9 24576.3 24643.4 8243.8 8254.2 8257.5 8418.2 8464.1 4404.3 4482.4 4500.5 4547.3 4605.0 3552.9 3699.9 3711.8 3717.5 3726.5 694.3 727.6 745.7 746.2 750.4 520.0 595.4 607.5 617.5 689.9 8799.5 9019.8 9329.8 9597.4 9636.3 645.7 649.9 674.8 677.9 684.6 4442.1 4469.3 4514.5 4557.7 4598.3 126228.0 165246.0 165715.0 169745.0 171230.0 145138.0 145925.0 146084.3 147193.0 152622.0 84140.0 123119.0 123125.0 123398.0 125550.4 7519.2 7911.2 8680.4 8893.1 9063.3 51850.7 66267.0 66378.3 66404.7 68775.3 63030.4 77379.6 101646.0 101754.0 102715.0 12358.0 12533.3 12536.2 12888.5 12918.4 12704.4 12922.0 13043.5 13057.2 13304.0 12446.8 12508.6 12861.9 12871.7 12883.0 12443.6 12835.8 13135.0 13605.9 14069.2 11642.3 13814.0 13882.9 13942.2 13961.5 10626.6 11168.6 11281.3 11563.3 11605.7 9272.4 9645.4 9753.0 9778.0 9886.6 02:44:07 INFO - PROCESS | 1932 | -------- Summary: end -------- 02:44:07 INFO - PROCESS | 1932 | 02:44:07 INFO - TEST-INFO | 1932: exit 0 02:44:07 INFO - TEST-OK | dromaeo_css | took 1115976ms 02:44:07 INFO - TEST-START | kraken 02:44:07 INFO - operating with platform_type : mac_ 02:44:07 INFO - Initialising browser for kraken test... 02:44:08 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpw4BajK/profile http://localhost:52230/getInfo.html) 02:44:10 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 02:44:10 INFO - PROCESS | 1937 | colorDepth:24 02:44:10 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 02:44:10 INFO - PROCESS | 1937 | __metrics 02:44:10 INFO - PROCESS | 1937 | 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" 02:44:10 INFO - PROCESS | 1937 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:44:11 INFO - TEST-INFO | 1937: exit 0 02:44:11 INFO - Browser initialized. 02:44:11 INFO - Running cycle 1/1 for kraken test... 02:44:11 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpw4BajK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:44:23 INFO - PROCESS | 1938 | RSS: Main: 244199424 02:44:23 INFO - PROCESS | 1938 | 02:44:26 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52230/tests/kraken/driver.html?testName=audio-beat-detection) 02:44:26 INFO - PROCESS | 1938 | RSS: Main: 294436864 02:44:26 INFO - PROCESS | 1938 | 02:44:29 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52230/tests/kraken/driver.html?testName=audio-dft) 02:44:30 INFO - PROCESS | 1938 | RSS: Main: 304738304 02:44:30 INFO - PROCESS | 1938 | 02:44:33 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52230/tests/kraken/driver.html?testName=audio-fft) 02:44:34 INFO - PROCESS | 1938 | RSS: Main: 389443584 02:44:34 INFO - PROCESS | 1938 | 02:44:36 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52230/tests/kraken/driver.html?testName=audio-oscillator) 02:44:36 INFO - PROCESS | 1938 | RSS: Main: 290447360 02:44:36 INFO - PROCESS | 1938 | 02:44:38 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:44:39 INFO - PROCESS | 1938 | RSS: Main: 294711296 02:44:39 INFO - PROCESS | 1938 | 02:44:42 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52230/tests/kraken/driver.html?testName=imaging-darkroom) 02:44:42 INFO - PROCESS | 1938 | RSS: Main: 327929856 02:44:42 INFO - PROCESS | 1938 | 02:44:45 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52230/tests/kraken/driver.html?testName=imaging-desaturate) 02:44:46 INFO - PROCESS | 1938 | RSS: Main: 332410880 02:44:46 INFO - PROCESS | 1938 | 02:44:49 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52230/tests/kraken/driver.html?testName=json-parse-financial) 02:44:49 INFO - PROCESS | 1938 | RSS: Main: 330309632 02:44:49 INFO - PROCESS | 1938 | 02:44:51 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:44:52 INFO - PROCESS | 1938 | RSS: Main: 312209408 02:44:52 INFO - PROCESS | 1938 | 02:44:53 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52230/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:44:54 INFO - PROCESS | 1938 | RSS: Main: 318656512 02:44:54 INFO - PROCESS | 1938 | 02:44:56 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:44:57 INFO - PROCESS | 1938 | RSS: Main: 319303680 02:44:57 INFO - PROCESS | 1938 | 02:45:00 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:45:00 INFO - PROCESS | 1938 | RSS: Main: 325459968 02:45:00 INFO - PROCESS | 1938 | 02:45:03 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:45:04 INFO - PROCESS | 1938 | RSS: Main: 326696960 02:45:04 INFO - PROCESS | 1938 | 02:45:05 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52230/tests/kraken/driver.html?testName=ai-astar) 02:45:06 INFO - PROCESS | 1938 | RSS: Main: 370638848 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | __start_tp_report 02:45:06 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 02:45:06 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 02:45:06 INFO - PROCESS | 1938 | |i|pagename|runs| 02:45:06 INFO - PROCESS | 1938 | |0;ai-astar;101;112;111;112;112;110;113;110;111;111 02:45:06 INFO - PROCESS | 1938 | |1;audio-beat-detection;143;108;106;158;108;104;129;107;140;105 02:45:06 INFO - PROCESS | 1938 | |2;audio-dft;183;183;188;182;184;184;180;184;183;186 02:45:06 INFO - PROCESS | 1938 | |3;audio-fft;80;73;59;105;56;57;62;69;83;60 02:45:06 INFO - PROCESS | 1938 | |4;audio-oscillator;85;88;92;70;70;67;95;69;68;101 02:45:06 INFO - PROCESS | 1938 | |5;imaging-gaussian-blur;87;84;87;83;87;87;87;84;86;83 02:45:06 INFO - PROCESS | 1938 | |6;imaging-darkroom;93;93;93;94;93;103;87;125;142;93 02:45:06 INFO - PROCESS | 1938 | |7;imaging-desaturate;84;84;86;85;86;84;86;85;102;134 02:45:06 INFO - PROCESS | 1938 | |8;json-parse-financial;56;56;55;54;54;55;55;54;53;53 02:45:06 INFO - PROCESS | 1938 | |9;json-stringify-tinderbox;67;71;81;75;72;69;70;70;71;72 02:45:06 INFO - PROCESS | 1938 | |10;stanford-crypto-aes;85;84;83;81;83;84;84;84;82;87 02:45:06 INFO - PROCESS | 1938 | |11;stanford-crypto-ccm;146;146;152;136;132;130;135;134;181;138 02:45:06 INFO - PROCESS | 1938 | |12;stanford-crypto-pbkdf2;162;161;161;162;162;162;162;162;161;160 02:45:06 INFO - PROCESS | 1938 | |13;stanford-crypto-sha256-iterative;66;66;69;66;67;66;64;63;68;80 02:45:06 INFO - PROCESS | 1938 | __end_tp_report 02:45:06 INFO - PROCESS | 1938 | __start_cc_report 02:45:06 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,1996 02:45:06 INFO - PROCESS | 1938 | __end_cc_report 02:45:06 INFO - PROCESS | 1938 | __startTimestamp1468316706249__endTimestamp 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | ------- Summary: start ------- 02:45:06 INFO - PROCESS | 1938 | Number of tests: 14 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.50 stddev:3.40 (3.1%) stddev-sans-first:1.00 02:45:06 INFO - PROCESS | 1938 | Values: 101.0 112.0 111.0 112.0 112.0 110.0 113.0 110.0 111.0 111.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#1] audio-beat-detection Cycles:10 Average:120.80 Median:118.50 stddev:19.95 (16.8%) stddev-sans-first:19.47 02:45:06 INFO - PROCESS | 1938 | Values: 143.0 108.0 106.0 158.0 108.0 104.0 129.0 107.0 140.0 105.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:2.16 (1.2%) stddev-sans-first:2.28 02:45:06 INFO - PROCESS | 1938 | Values: 183.0 183.0 188.0 182.0 184.0 184.0 180.0 184.0 183.0 186.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#3] audio-fft Cycles:10 Average:70.40 Median:65.50 stddev:15.46 (23.6%) stddev-sans-first:16.01 02:45:06 INFO - PROCESS | 1938 | Values: 80.0 73.0 59.0 105.0 56.0 57.0 62.0 69.0 83.0 60.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:77.50 stddev:13.04 (16.8%) stddev-sans-first:13.73 02:45:06 INFO - PROCESS | 1938 | Values: 85.0 88.0 92.0 70.0 70.0 67.0 95.0 69.0 68.0 101.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:87.00 stddev:1.78 (2.0%) stddev-sans-first:1.80 02:45:06 INFO - PROCESS | 1938 | Values: 87.0 84.0 87.0 83.0 87.0 87.0 87.0 84.0 86.0 83.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:93.00 stddev:17.71 (19.0%) stddev-sans-first:18.51 02:45:06 INFO - PROCESS | 1938 | Values: 93.0 93.0 93.0 94.0 93.0 103.0 87.0 125.0 142.0 93.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:85.00 stddev:15.85 (18.6%) stddev-sans-first:16.57 02:45:06 INFO - PROCESS | 1938 | Values: 84.0 84.0 86.0 85.0 86.0 84.0 86.0 85.0 102.0 134.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.08 (2.0%) stddev-sans-first:1.00 02:45:06 INFO - PROCESS | 1938 | Values: 56.0 56.0 55.0 54.0 54.0 55.0 55.0 54.0 53.0 53.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.50 stddev:3.85 (5.4%) stddev-sans-first:3.67 02:45:06 INFO - PROCESS | 1938 | Values: 67.0 71.0 81.0 75.0 72.0 69.0 70.0 70.0 71.0 72.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.64 (1.9%) stddev-sans-first:1.67 02:45:06 INFO - PROCESS | 1938 | Values: 85.0 84.0 83.0 81.0 83.0 84.0 84.0 84.0 82.0 87.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:142.00 stddev:15.10 (10.6%) stddev-sans-first:15.98 02:45:06 INFO - PROCESS | 1938 | Values: 146.0 146.0 152.0 136.0 132.0 130.0 135.0 134.0 181.0 138.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:162.00 stddev:0.71 (0.4%) stddev-sans-first:0.73 02:45:06 INFO - PROCESS | 1938 | Values: 162.0 161.0 161.0 162.0 162.0 162.0 162.0 162.0 161.0 160.0 02:45:06 INFO - PROCESS | 1938 | 02:45:06 INFO - PROCESS | 1938 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:66.50 stddev:4.72 (7.1%) stddev-sans-first:4.97 02:45:06 INFO - PROCESS | 1938 | Values: 66.0 66.0 69.0 66.0 67.0 66.0 64.0 63.0 68.0 80.0 02:45:06 INFO - PROCESS | 1938 | -------- Summary: end -------- 02:45:06 INFO - PROCESS | 1938 | 02:45:07 INFO - TEST-INFO | 1938: exit 0 02:45:07 INFO - TEST-OK | kraken | took 59100ms 02:45:07 INFO - SUITE-END | took 1175s 02:45:07 INFO - Completed test suite (00:19:35) 02:45:07 INFO - javascript benchmark 02:45:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3100.11646680696, 3318.7842790218633, 3320.1201681554376, 3360.395128876801, 3381.6787405196515, 5563.833085007446, 5619.522340601049, 5638.689872057035, 5710.229119068925, 5755.107958266465, 5646.2659854219, 5661.490465858072, 5763.625364351325, 5770.422957704228, 5783.566232532556, 5064.442911279774, 5086.440491545927, 5142.100132476801, 5160.14857548504, 5224.660397074197, 5214.843554693365, 5257.185136303879, 5269.262303277545, 5280.894382112375, 5298.735063246822, 5081.212412076134, 5089.312942752755, 5109.3613298337705, 5128.9230661540105, 5157.5358217760395, 5104.795808167706, 5117.1556693145585, 5125.487451254845, 5129.743512824344, 5144.717040562768, 5112.590992720573, 5134.948650513485, 5136.871578210537, 5151.562117220041, 5175.948240517584, 5524.889502209973, 5591.412901645309, 5690.743916523725, 5721.5708821838525, 5785.132230165466, 5091.79632814687, 6148.262208534959, 6204.906926396107, 6218.160548325964, 6218.191635087455, 6223.7821676241565, 6268.780592679234, 6288.0, 6290.622562646256, 6298.590591611531, 5619.831405057855, 5666.773329066791, 5759.452851979056, 5806.1000544915605, 5807.477327040587, 6278.717457714414, 6288.465480434214, 6296.307406185316, 6308.968455157695, 6312.5581209314905, 6126.908096378558, 6131.294901086343, 6137.0, 6138.416850399205, 6142.6314421134875, 5987.31145918225, 6124.441964285778, 6128.105930682418, 6223.159873417121, 6243.469305109116, 6123.557162997653, 6141.324454310023, 6184.257889053252, 6190.81427557174, 6191.752329906843, 6175.62946223228, 6191.721372538156, 6199.977003794414, 6219.40915613016, 6222.875542489125, 4823.5658790709, 4847.064871384187, 4917.557419832161, 4957.652964292465, 4995.050940321327, 4424.6902716810155, 4462.286034234572, 4479.0, 4480.798364073625, 4485.775711214426, 4944.53026962438, 5498.422665620133, 5619.157126431068, 5645.717714114277, 5657.0, 5145.76844042019, 6125.571219981412, 6156.860980718581, 6214.25428948529, 6219.346968568326, 5678.665548337137, 5689.402612725687, 5695.288088988877, 5699.487046165782, 5712.971435142881, 5459.617826752089, 5477.096279113902, 5477.260569823103, 5482.506574408243, 5483.067878460672, 5184.844454666366, 6205.131281620576, 6254.968725156436, 6269.467095296951, 6352.364763523796, 6168.1672974149715, 6168.599041062316, 6197.504199664107, 6213.347598502273, 6224.657643829632, 6114.966544785144, 6339.746410143543, 6340.524460665376, 6357.332480089524, 6360.809175724736, 6104.26748790148, 6108.311716708011, 6121.418465245794, 6175.073738939195, 6183.628982261079, 6140.754369825157, 6149.108379285068, 6149.2620885494025, 6167.074938759222, 6178.845528861814, 5269.656237659372, 5940.821775346746, 6075.453209211192, 6077.604955677867, 6103.786367477294, 5902.734376952962, 6081.483073938588, 6158.076288556752, 6166.044263139221, 6171.876562468815, 6000.399960003964, 6011.819645410645, 6034.517238620812, 6060.0, 6090.9390906090375, 5900.468957793819, 6044.909326360196, 6065.636061836304, 6144.170536977451, 6152.692365381624, 5958.314793798769, 5964.493018093337, 5978.461938425563, 5979.40205979416, 5994.190784244071], "value": 5654.2865886532, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.681449094316, 526.9973650131749, 529.655723779543, 549.3023859698184, 564.9152627105933, 30288.788448462063, 40863.79568102159, 42164.78917605407, 42202.7889860551, 42357.99999999996, 42674.573254267445, 42913.0, 43053.7847310763, 43055.35416968748, 43157.136857262834, 8589.0, 9648.999999999982, 9809.460479673651, 9826.06652368024, 9833.16418104462, 8587.785305367353, 11699.883001170007, 11798.351090690008, 11802.7639447211, 12108.93945530271, 11795.9383655471, 11865.584704535342, 11878.703032424215, 11882.64352069435, 11883.70290742734, 8546.145385461405, 11572.53709851605, 11731.002864756421, 11790.17468777186, 11868.52525898963, 11642.24325418845, 11716.88283117175, 11729.765404691858, 11733.706657333634, 11735.823962640567, 40076.19847603032, 40423.59576404228, 40444.393334100016, 40489.19021619551, 40518.59481405207, 3443.9655603444144, 3453.861845526176, 3587.157018100744, 3623.800690961996, 3766.133789228493, 28481.145565633065, 29592.70407295921, 32473.51289730656, 32603.18492037718, 33030.0, 3261.6901394367733, 3324.700776930088, 3336.9499457508155, 3340.6659334066644, 3447.3450044491465, 492386.53806731093, 492522.0, 495625.0, 497047.0, 497074.0, 58795.706021470476, 59191.112133318034, 59198.40801592015, 59201.40798591959, 59299.40700592939, 60789.0, 61668.383316167165, 61719.382806171365, 61795.07307390393, 61932.45168870814, 16224.67550649004, 16492.83507164937, 16513.91743041301, 16525.25636346367, 16541.090240036912, 11671.649850504498, 11920.761584768255, 12652.240865548096, 12701.0, 12941.482340706267, 15515.534533963999, 15516.301766420538, 15544.145072021147, 15579.688406231811, 15764.448244311394, 5878.2358293421585, 5896.646201227854, 6651.603163335657, 6741.224759152662, 6805.6597170141295, 782.4210084537515, 890.4479222881855, 914.446759710379, 1022.897710228986, 1103.4372470040337, 30119.69880301213, 30407.543886841715, 30520.69479305223, 31143.68856311453, 31355.843220784205, 1060.2154405739698, 1107.1198397274025, 1117.5027112934665, 1167.9532818687157, 1181.0, 15407.46073887431, 15618.90667653276, 15922.522324330288, 15958.521244362688, 16326.0, 607164.9641751852, 613568.0, 616938.0, 621554.0, 624308.0, 58327.708361457924, 58412.12381814276, 58567.0, 58592.0, 58691.41308586945, 1097.0729733375285, 1280.187081203424, 1417.6951955329653, 1447.0666420159148, 1448.0732331307972, 29918.80324786984, 31562.210944725648, 31718.524222136224, 31765.68234317627, 32345.51481727729, 48545.27182092201, 49363.506364937326, 49486.75256623838, 49533.50466495289, 49547.00905981932, 497973.0, 499553.0, 505400.0, 506332.0, 513038.0, 34249.82875085609, 40366.79816600898, 40373.39439908345, 41233.58766412298, 42405.0, 38241.0, 48522.5147748518, 49596.25605615846, 49596.75201623969, 49798.0, 38500.0, 39543.80228098841, 40056.798296051165, 40871.1825763489, 41004.35816164794, 39906.80046599748, 40131.0, 40813.38779918244, 40952.59047409607, 41321.96695082647, 32216.35567288688, 38470.0, 39396.0, 39447.81656550308, 41187.794061029505, 33856.66143338535, 34819.40832168816, 35386.64613353936, 35421.82289088538, 36152.63847361598, 22486.662700059183, 23939.401514962265, 25639.487210256062, 25704.228873133838, 25877.0], "value": 20489.09258783075, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.89923023093075, 353.90621485306417, 371.6673577148452, 385.452657226738, 406.4594089860484, 15741.291641876114, 17038.65922681547, 21254.0, 21385.99999999998, 21419.357419277407, 21419.571608567818, 21946.561068778614, 22370.88814555929, 22586.43533911653, 22621.868906554646, 5211.739413029352, 5492.697901615409, 5560.77756889724, 6041.154238406627, 6058.4244496772735, 4160.375943608453, 4403.405540252057, 5905.379935106816, 6337.144485494446, 6667.233268174174, 6352.237731472207, 6627.536072474929, 6639.568428052161, 6644.767433139841, 6660.367265109807, 6616.669166541702, 6640.900386494206, 6647.76732814354, 6648.202215734119, 6649.501287403464, 5259.421463638997, 5563.248961390201, 5572.86067848307, 5787.855303617401, 5791.189233507313, 13743.931280343635, 13847.892168626486, 13924.094933829268, 13999.160050396908, 14111.435542578285, 2307.492351682627, 2350.9059637614646, 2384.1536254629705, 2419.27421773469, 2470.962935555968, 10033.849492257623, 10604.787904241872, 10731.141508679288, 14934.32795524204, 16369.181540922906, 1817.836394724468, 1867.6078023615057, 1962.2641509433963, 1967.3507742444958, 1986.8708533945248, 105645.0, 107358.0, 109652.0, 120825.0, 122724.0, 11390.487428065757, 11609.477573509044, 11637.47631356591, 11707.414629268502, 11824.349660768741, 14670.0, 14757.0, 14781.92609036948, 14791.408343666348, 14810.185439800698, 8632.870506942401, 8645.567721613894, 8674.739757807276, 8715.389922705477, 8762.299016078698, 8691.521966291915, 8704.347173961978, 8725.694600688945, 8798.560071996375, 8804.603792829337, 4602.125596136723, 4610.907781844345, 4628.83799067031, 4632.397788287569, 4683.2038553446, 1495.4541592318726, 1597.5526852481323, 1770.1408721111766, 1824.5256233379416, 1976.4861136104716, 867.8568036274071, 883.6951251818112, 936.9672061477816, 1001.369137443406, 1019.9439030853374, 17682.381116660854, 23688.40778980539, 23712.40718982039, 24022.15922442741, 24571.3857153569, 922.8477301245354, 963.3689933093834, 970.2092794372705, 1009.7576581620358, 1032.8450732390052, 11593.768124637629, 11835.99394051498, 11993.400329983466, 12045.819312710317, 12098.697532561757, 513946.43026784627, 522849.0, 522930.0, 607941.0, 615013.0, 82207.17792822115, 141677.0, 142445.57554424324, 151635.0, 151736.72394914084, 943.235978857128, 963.816874793787, 993.3096497934011, 994.472929347447, 1014.8224060789332, 19282.606651256992, 19922.79063774523, 19957.01017161189, 20000.199006955478, 20002.98433225566, 53047.0, 56788.0, 59624.0, 60129.398706012376, 60479.69760151171, 87024.5648771752, 88139.0, 88695.0, 89115.0, 90399.0, 8089.191080891864, 8227.876581851397, 8368.874466883124, 8560.785980350542, 8615.655373785228, 11737.178397512434, 13192.604221873358, 13196.538121165713, 13302.268375239455, 13378.063535552807, 11367.48846301935, 11379.317240965569, 11389.202755807346, 11399.544018239509, 11407.144464165054, 10102.494875256354, 11011.339319640847, 11176.552937882394, 11240.494177762184, 11439.485223164811, 8700.859692450882, 10473.0, 11295.943520282346, 11414.315141091562, 11680.824787628022, 7903.960480197562, 8090.79773005656, 8312.6674933002, 8348.87476687838, 8379.790505237417, 10909.727256818644, 11318.547258109913, 11320.717230417788, 11331.433428328386, 11398.0], "value": 9662.949530417029, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.4188469900294, 1508.411719429422, 1700.2433916906973, 1878.1360574135897, 1905.8951757653324, 7883.172266911978, 8142.552159631218, 8317.958410207948, 8855.690050848221, 8872.112788721133, 8308.667653293876, 8563.400561960665, 8587.184217499344, 8728.258098061657, 8735.432196907197, 2277.339571524256, 2345.4488195274184, 2348.236823032513, 2349.048635302705, 2357.599208134621, 2059.093998640601, 2071.264701031135, 2098.1083039708137, 2160.708304378912, 2162.4593851537115, 2121.819645330149, 2135.135270215565, 2250.0437314141504, 2331.277304035746, 2332.4402143485527, 2301.6662583925236, 2331.2773040357542, 2336.6611841283, 2340.754220806808, 2343.7656234376595, 2254.109626697462, 2324.628059510487, 2334.9299521014227, 2338.310198491432, 2342.133410638075, 8704.521251331174, 8930.910690893073, 8960.01439841617, 8964.238039766627, 8968.192862642329, 5194.142966410538, 5295.285136506599, 5329.467053294678, 5331.93361327736, 5333.57331413486, 3407.4718418645148, 3448.9827550862337, 3476.8609255629744, 3510.6489351064943, 3519.964800351989, 1084.5553323137476, 1085.0451602589749, 1090.7600327927844, 1098.8022156011914, 1105.8783533811354, 11470.139739519569, 11535.884641153565, 11536.826947595793, 11551.480183391683, 11556.248843825124, 4306.0957198987935, 4329.826806927751, 4532.98007948209, 4547.909041819211, 4555.362249285103, 9125.133112354315, 9130.543472826332, 9146.54267286633, 9150.496722680316, 9150.633974641072, 4264.616184543406, 5183.50756678115, 5185.299984502044, 5190.948090519122, 5195.766190521435, 4759.190937540627, 4865.172920603435, 4867.367242258554, 4871.732054737024, 4875.975620121877, 3559.5728512578658, 3585.982070089685, 3616.168281295264, 3666.138457462494, 3668.0463079599494, 1032.055669062814, 1038.9064984151462, 1040.9167266618651, 1043.1446214104512, 1075.6772968109535, 629.7638385605399, 880.221004411088, 921.7280902133887, 942.2838642631513, 948.2177203806887, 5859.414058594191, 5928.67392293428, 6049.455549000611, 6068.423499767515, 6207.534434917399, 896.4825717037104, 913.4017218721748, 936.9740134552654, 961.4904100826573, 983.0759086458706, 3823.5794062653085, 3900.356441187229, 4032.536258330271, 4140.4617399737845, 4148.543660197376, 10295.43375114361, 10723.785524289626, 10768.030877220932, 10770.138388928866, 10784.622538211124, 9642.903570964201, 10170.186385089175, 10557.419341936115, 10747.946260268649, 10762.56949722018, 6924.34218749208, 8076.27313541784, 8279.668813247523, 8296.626651800683, 8305.95847020761, 5524.83978364548, 5865.0, 6050.606710563799, 6132.141500190066, 6265.467435268053, 9839.950800245953, 10036.096751292418, 10048.849267261143, 10062.748431289276, 10065.899341006789, 8635.093315202064, 9746.976567460599, 10119.797604048024, 10207.387556746618, 10237.23220758461, 5911.674857882857, 6990.9300906990275, 7078.0, 7079.681414336264, 7169.605671688108, 8194.918050819415, 8444.704435344733, 8446.577671116542, 8448.957755211184, 8450.619722112397, 5948.533812088535, 7040.894386584103, 7078.115235595551, 7095.325944035309, 7192.0, 7162.534435261691, 7192.964035179791, 7225.0, 7257.52826066304, 7302.0, 7027.15674119109, 7054.365107140382, 7092.680829362588, 7108.46686498525, 7113.75101871432, 4874.805007799649, 4898.8775280618265, 4975.502449754996, 4976.527229913152, 5057.772400242072, 4937.456879743298, 4988.625853061108, 5020.648554601143, 5164.04465173944, 5169.379674439091], "value": 4482.5875817892675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.3728160956803, 264.4182797844741, 308.42786630799867, 318.5317583152765, 322.39389946899826, 4493.438320209973, 5339.839804805857, 5361.544268737162, 5385.946140538598, 5426.403095659479, 4150.920760602243, 5204.817831375903, 5287.625217443464, 5350.732463376826, 5354.839354819352, 3112.4553203189407, 3751.1559899022773, 3860.613938606131, 3892.2020985698005, 4045.3931910213555, 3353.983230083846, 4031.717779755418, 4043.5552089270304, 4062.1469491406842, 4101.302778527657, 3350.48067549529, 3394.353738436856, 4050.554439011721, 4106.568810274923, 4113.9177216455655, 3845.5193100862393, 3929.25344184604, 3979.5622481527007, 4078.5921407859123, 4093.774842383668, 2676.1703871799805, 3528.7353448491467, 3543.5039094526787, 3560.0209942265824, 3592.3533763922483, 5149.124648809711, 5173.379194496647, 5189.974050129726, 5210.557102646279, 5234.502722241381, 4356.302991521373, 4404.889877753049, 4581.473130589991, 4654.534546545318, 4743.35964644772, 5597.888042239173, 5602.60781745278, 5627.876528526666, 5632.831015069554, 5650.632708873951, 3945.230680017398, 3973.5099337748343, 3974.960250397488, 3980.925150209427, 3983.223271462066, 5283.920741188886, 5330.413654498002, 5777.162311464814, 5863.2670916135485, 5874.475314811122, 3568.8750893718593, 3851.441540976543, 3967.107400834847, 3974.7217694761666, 4007.6793856491413, 3326.3679900818765, 3743.494628225155, 4092.836286548564, 4115.074108325663, 4124.391487319947, 4412.146898302721, 4679.836205732783, 4686.687727436323, 4722.3318824863845, 4727.716337019721, 5297.629165958423, 5399.892002160013, 5580.2466666999435, 5602.2717046783655, 5741.282339707536, 1188.8157335612993, 1214.6538236602703, 1406.4936622815778, 1408.5140626483847, 1415.3489394878266, 730.6711979609197, 824.4105464592725, 873.9082396348292, 876.7325965580432, 882.5102068352127, 4927.975360123248, 5015.448300686921, 5096.941834899105, 5173.0, 5185.0, 4781.6891902026255, 5693.4875861172695, 5714.342850572238, 5748.741306641211, 5767.452092051249, 4366.454193225847, 4573.885652858638, 4852.441969173519, 5103.974480127576, 5177.844664660066, 2588.2235329401105, 2993.985030074836, 2994.8053376530893, 2998.78090213245, 3012.8041677291344, 5777.364489906107, 5887.528997680175, 5893.793717219963, 5909.881802364014, 5915.231019967377, 4920.803167873317, 5333.333333333333, 5537.695426751487, 5967.104934259809, 6022.217111775529, 5309.3894202166475, 5514.338279406497, 5987.6108052976415, 6007.36922623127, 6062.636241825505, 5496.697681627549, 5729.427057294237, 5810.389909059488, 5870.26621672291, 5872.295324560909, 5035.874103147451, 5725.79758250772, 5760.423957604206, 5795.130730390475, 5797.695518508302, 5462.617616766788, 5702.771889124555, 5787.543591434656, 6030.7286172121485, 6062.514998800174, 3398.014575773097, 3626.4560315951762, 3831.885043448701, 3886.6696330812197, 3888.8833334999995, 5728.455796699307, 5736.541076713769, 5738.397468265939, 5762.913556296743, 5768.567357448124, 4749.548792864673, 5032.119379108714, 5600.663960162403, 5608.850185712001, 5641.435856414326, 4801.7118972861745, 5329.0, 5674.2623458950075, 5684.744186511534, 5685.090385538294, 4668.486466488615, 5274.235235890851, 5561.527270181918, 5583.497485226349, 5591.356993945586, 4506.174999125359, 5335.611886452053, 5557.583181261502, 5687.829365119053, 5711.657300561979, 4423.823047078209, 5545.0, 5552.861178470571, 5561.972190139023, 5603.607747457801], "value": 4081.9433091019896, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.349805669068, 839.0895877972399, 840.5293035899897, 861.1259571534891, 879.3580686099151, 15238.466653667125, 16125.83874161258, 16596.751048734262, 16623.66752664949, 16632.833671663295, 15035.0, 15147.54557363278, 15147.848521514781, 15188.0, 15456.922715386407, 716.3874886971643, 737.6680493777794, 881.607684580362, 899.5277479323343, 904.8778414914001, 715.4205093873953, 719.543090137761, 719.8596273726625, 721.90976127984, 862.2196911794836, 948.5494390164686, 956.741679746468, 957.3203025851653, 958.4441024205979, 959.2517836087862, 793.8769490728918, 948.7390967483929, 952.766572189811, 954.0650162840456, 955.5938726041475, 537.5484592941948, 564.4496615799562, 564.8220810444697, 692.0829900382014, 697.9336962988508, 12608.621741347773, 12646.114771965875, 15520.844791552054, 15832.97085689438, 15845.603859903595, 14509.782353264709, 15225.162616056112, 15316.693666126615, 15362.308696108701, 15375.461858834888, 10315.381077135396, 12060.0, 12153.878461215363, 12325.383730813513, 12526.311052892088, 3300.884469043572, 3797.9810100949594, 3856.325143099946, 3927.56796752357, 4060.3909413587903, 112303.43848280788, 112597.0, 112621.31068033986, 113726.0, 116806.99999999916, 11678.941605291919, 11688.934635101752, 11700.414979251003, 11788.0, 12260.203086799329, 20389.388318350473, 24184.51630967406, 24566.877165614416, 24576.262712118663, 24643.3839154019, 8243.83512329762, 8254.215849494289, 8257.504549726915, 8418.158184181655, 8464.111268316738, 4404.2512772828695, 4482.417285752831, 4500.549945005473, 4547.272436410159, 4604.97697511517, 3552.91117722059, 3699.8890033299044, 3711.758735682226, 3717.4609681596557, 3726.5341832270965, 694.2779509310361, 727.5634619228526, 745.7240820896195, 746.2015643261766, 750.3959312753286, 520.0015969338879, 595.4105435618707, 607.4623957797381, 617.4905702795212, 689.8896176611718, 8799.516026618598, 9019.819603607892, 9329.813403731887, 9597.424154550748, 9636.277279204087, 645.6932956845461, 649.878958796073, 674.8110529051874, 677.9118714567074, 684.6268783512998, 4442.088891310432, 4469.307257375112, 4514.458265008155, 4557.726536407826, 4598.333241680005, 126228.0, 165246.0, 165715.0, 169745.0, 171230.0, 145138.0, 145925.0, 146084.26957865356, 147193.0, 152622.0, 84140.0, 123119.0, 123125.0, 123398.0, 125550.37224813817, 7519.248075192437, 7911.208879112043, 8680.392372533863, 8893.107841422949, 9063.32025098107, 51850.74074629754, 66267.00599491107, 66378.33621663721, 66404.66797665981, 68775.31224687689, 63030.36969630245, 77379.61310193413, 101646.0, 101754.0, 102715.0, 12358.0, 12533.310667912987, 12536.247825130522, 12888.48446062147, 12918.418671160009, 12704.36478176106, 12922.008679479231, 13043.543475978295, 13057.151285166434, 13304.002199834858, 12446.751064978469, 12508.562200322946, 12861.935690321488, 12871.742565148832, 12883.0, 12443.626691199279, 12835.844773970388, 13135.0, 13605.863941360856, 14069.226192559507, 11642.301461912313, 13814.0, 13882.861171388156, 13942.233177175352, 13961.511347102803, 10626.628068017582, 11168.609098681507, 11281.266717663326, 11563.30620162793, 11605.6518304451, 9272.350935434455, 9645.42127472354, 9752.951235243778, 9778.0, 9886.555105020147], "value": 7890.4427367802855, "unit": "score"}], "name": "dromaeo_css", "value": 7380.6157605571425, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 111.0, 112.0, 112.0, 110.0, 113.0, 110.0, 111.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 106.0, 158.0, 108.0, 104.0, 129.0, 107.0, 140.0, 105.0], "value": 120.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 188.0, 182.0, 184.0, 184.0, 180.0, 184.0, 183.0, 186.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 59.0, 105.0, 56.0, 57.0, 62.0, 69.0, 83.0, 60.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 92.0, 70.0, 70.0, 67.0, 95.0, 69.0, 68.0, 101.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 83.0, 87.0, 87.0, 87.0, 84.0, 86.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 103.0, 87.0, 125.0, 142.0, 93.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 85.0, 86.0, 84.0, 86.0, 85.0, 102.0, 134.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 54.0, 54.0, 55.0, 55.0, 54.0, 53.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 81.0, 75.0, 72.0, 69.0, 70.0, 70.0, 71.0, 72.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 83.0, 81.0, 83.0, 84.0, 84.0, 84.0, 82.0, 87.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 152.0, 136.0, 132.0, 130.0, 135.0, 134.0, 181.0, 138.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0, 161.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 69.0, 66.0, 67.0, 66.0, 64.0, 63.0, 68.0, 80.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1426.3999999999999, "alertThreshold": 2.0}]} 02:45:07 INFO - Return code: 0 02:45:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:45:07 INFO - # TBPL SUCCESS # 02:45:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:45:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:45:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:45:07 INFO - 2016-07-12 02:45:07.536 screenresolution[1942:14946] starting screenresolution argv=screenresolution get 02:45:07 INFO - 2016-07-12 02:45:07.539 screenresolution[1942:14946] Display 0: 1600x1200x32@60 02:45:07 INFO - 2016-07-12 02:45:07.545 screenresolution[1943:14950] starting screenresolution argv=screenresolution list 02:45:07 INFO - Available Modes on Display 0 02:45:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:45:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:45:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:45:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:45:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:45:07 INFO - Intel Iris: 02:45:07 INFO - Chipset Model: Intel Iris 02:45:07 INFO - Type: GPU 02:45:07 INFO - Bus: Built-In 02:45:07 INFO - VRAM (Dynamic, Max): 1536 MB 02:45:07 INFO - Vendor: Intel (0x8086) 02:45:07 INFO - Device ID: 0x0a2e 02:45:07 INFO - Revision ID: 0x0009 02:45:07 INFO - Displays: 02:45:07 INFO - Display: 02:45:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:45:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:45:07 INFO - Display Serial Number: 1600x1200 60 02:45:07 INFO - Main Display: Yes 02:45:07 INFO - Mirror: Off 02:45:07 INFO - Online: Yes 02:45:07 INFO - Rotation: Supported 02:45:07 INFO - Return code: 0 02:45:07 INFO - Running post-action listener: _resource_record_post_action 02:45:07 INFO - ##### 02:45:07 INFO - ##### Finished run-tests step (success) 02:45:07 INFO - ##### 02:45:07 INFO - Running post-run listener: _resource_record_post_run 02:45:07 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 557056; Write bytes: 706933248; Read time: 11; Write time: 1964 02:45:07 INFO - TinderboxPrint: CPU usage
53.3% 02:45:07 INFO - TinderboxPrint: I/O read bytes / time
557,056 / 11 02:45:07 INFO - TinderboxPrint: I/O write bytes / time
706,933,248 / 1,964 02:45:07 INFO - TinderboxPrint: CPU idle
2,259.9 (46.7%) 02:45:07 INFO - TinderboxPrint: CPU system
966.0 (20.0%) 02:45:07 INFO - TinderboxPrint: CPU user
1,615.7 (33.4%) 02:45:07 INFO - TinderboxPrint: Swap in / out
406,790,144 / 0 02:45:07 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391586304; Write bytes: 388326912; Read time: 29281; Write time: 800 02:45:07 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 548864; Write bytes: 295566336; Read time: 9; Write time: 1108 02:45:08 INFO - Running post-run listener: _upload_blobber_files 02:45:08 INFO - Blob upload gear active. 02:45:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:45:08 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 02:45:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:45:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:45:08 INFO - (blobuploader) - INFO - Open directory for files ... 02:45:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:45:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:45:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:09 INFO - (blobuploader) - INFO - Done attempting. 02:45:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:45:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:45:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:10 INFO - (blobuploader) - INFO - Done attempting. 02:45:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:45:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:45:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:45:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:45:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:45:12 INFO - (blobuploader) - INFO - Done attempting. 02:45:12 INFO - (blobuploader) - INFO - Iteration through files over. 02:45:12 INFO - Return code: 0 02:45:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:45:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:45:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/678788fd02e2058b128a84b073a0d7ee625a60cf3b70574bb5e0a8fbd35e9399335f800d1e7b4e4096331770fe3c10f42e6759bad6d1a2493fccdab38d8f1f80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3aea5853dae2627a263528740b297ce6af9178027b63e25d7e06f6f215ef1ed7148aa5cdb2542372805a186a79d74039e5585def0ef74009129e89a92527652", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f0c3b24de4bf4a0b901378067e4b3efd3506c855d03ffaa95bab23600d84f0179fac85eeea02ed0a3a45bf0919b50a5dab35d145c87309837b72971f3b08566"} 02:45:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:45:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:45:12 INFO - Contents: 02:45:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/678788fd02e2058b128a84b073a0d7ee625a60cf3b70574bb5e0a8fbd35e9399335f800d1e7b4e4096331770fe3c10f42e6759bad6d1a2493fccdab38d8f1f80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3aea5853dae2627a263528740b297ce6af9178027b63e25d7e06f6f215ef1ed7148aa5cdb2542372805a186a79d74039e5585def0ef74009129e89a92527652", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f0c3b24de4bf4a0b901378067e4b3efd3506c855d03ffaa95bab23600d84f0179fac85eeea02ed0a3a45bf0919b50a5dab35d145c87309837b72971f3b08566"} 02:45:12 INFO - Running post-run listener: copy_logs_to_upload_dir 02:45:12 INFO - Copying logs to upload dir... 02:45:12 INFO - mkdir: /builds/slave/test/build/upload/logs 02:45:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.862783 ========= master_lag: 415.88 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-07-12 02:52:08.358615) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-12 02:52:08.362167) ========= 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.HMbc0RH06T/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.RF9AVI03tq/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/678788fd02e2058b128a84b073a0d7ee625a60cf3b70574bb5e0a8fbd35e9399335f800d1e7b4e4096331770fe3c10f42e6759bad6d1a2493fccdab38d8f1f80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3aea5853dae2627a263528740b297ce6af9178027b63e25d7e06f6f215ef1ed7148aa5cdb2542372805a186a79d74039e5585def0ef74009129e89a92527652", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f0c3b24de4bf4a0b901378067e4b3efd3506c855d03ffaa95bab23600d84f0179fac85eeea02ed0a3a45bf0919b50a5dab35d145c87309837b72971f3b08566"} build_url:https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011448 build_url: 'https://queue.taskcluster.net/v1/task/Qc1vESgOQSqKS10Da2thnQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/678788fd02e2058b128a84b073a0d7ee625a60cf3b70574bb5e0a8fbd35e9399335f800d1e7b4e4096331770fe3c10f42e6759bad6d1a2493fccdab38d8f1f80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e3aea5853dae2627a263528740b297ce6af9178027b63e25d7e06f6f215ef1ed7148aa5cdb2542372805a186a79d74039e5585def0ef74009129e89a92527652", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2f0c3b24de4bf4a0b901378067e4b3efd3506c855d03ffaa95bab23600d84f0179fac85eeea02ed0a3a45bf0919b50a5dab35d145c87309837b72971f3b08566"}' ========= master_lag: 1.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-12 02:52:09.409856) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 28 secs) (at 2016-07-12 02:52:09.410200) ========= 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.HMbc0RH06T/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.RF9AVI03tq/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.005051 ========= master_lag: 28.42 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 28 secs) (at 2016-07-12 02:52:37.839381) ========= ========= Started reboot skipped (results: 3, elapsed: 4 secs) (at 2016-07-12 02:52:37.839660) ========= ========= Finished reboot skipped (results: 3, elapsed: 4 secs) (at 2016-07-12 02:52:42.327052) ========= ========= Total master_lag: 445.41 =========